builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-032 starttime: 1496408667.07 results: success (0) revision: 194c009d6295597344662f8e20f5e309deffe8e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.072801) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.074158) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.074460) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.099164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.099542) ========= 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=745a4373a2a16df560f216e000000264-1496406752.244137-24443858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-02 06:04:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 229M=0s 2017-06-02 06:04:27 (229 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148952 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.270554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.270844) ========= 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=745a4373a2a16df560f216e000000264-1496406752.244137-24443858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018062 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.324931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:27.325239) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 194c009d6295597344662f8e20f5e309deffe8e9 --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 194c009d6295597344662f8e20f5e309deffe8e9 --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=745a4373a2a16df560f216e000000264-1496406752.244137-24443858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-02 06:04:27,526 truncating revision to first 12 chars 2017-06-02 06:04:27,526 Setting DEBUG logging. 2017-06-02 06:04:27,526 attempt 1/10 2017-06-02 06:04:27,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/194c009d6295?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 06:04:27,962 unpacking tar archive at: mozilla-central-194c009d6295/testing/mozharness/ program finished with exit code 0 elapsedTime=0.772931 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:28.112445) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:28.112717) ========= script_repo_revision: 194c009d6295597344662f8e20f5e309deffe8e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:28.113113) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:28.113442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 06:04:28.122158) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 0 secs) (at 2017-06-02 06:04:28.122438) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=745a4373a2a16df560f216e000000264-1496406752.244137-24443858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:04:28 INFO - MultiFileLogger online at 20170602 06:04:28 in /builds/slave/test 06:04:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:04:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:04:28 INFO - {'append_to_log': False, 06:04:28 INFO - 'base_work_dir': '/builds/slave/test', 06:04:28 INFO - 'blob_upload_branch': 'Firefox', 06:04:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:04:28 INFO - 'branch': 'Firefox', 06:04:28 INFO - 'buildbot_json_path': 'buildprops.json', 06:04:28 INFO - 'config_files': ('talos/linux_config.py',), 06:04:28 INFO - 'default_actions': ('clobber', 06:04:28 INFO - 'read-buildbot-config', 06:04:28 INFO - 'download-and-extract', 06:04:28 INFO - 'populate-webroot', 06:04:28 INFO - 'create-virtualenv', 06:04:28 INFO - 'install', 06:04:28 INFO - 'setup-mitmproxy', 06:04:28 INFO - 'run-tests'), 06:04:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:04:28 INFO - 'download_minidump_stackwalk': True, 06:04:28 INFO - 'download_symbols': 'ondemand', 06:04:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:04:28 INFO - 'tooltool.py': '/tools/tooltool.py', 06:04:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:04:28 INFO - '/tools/misc-python/virtualenv.py')}, 06:04:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:04:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:04:28 INFO - 'gecko_profile': False, 06:04:28 INFO - 'gecko_profile_interval': 0, 06:04:28 INFO - 'installer_path': 'installer.exe', 06:04:28 INFO - 'log_level': 'info', 06:04:28 INFO - 'log_name': 'talos', 06:04:28 INFO - 'log_to_console': True, 06:04:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:04:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:04:28 INFO - 'opt_config_files': (), 06:04:28 INFO - 'pip_index': False, 06:04:28 INFO - 'suite': 'dromaeojs-e10s', 06:04:28 INFO - 'system_bits': '32', 06:04:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:04:28 INFO - 'title': 'talos-linux64-ix-032', 06:04:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:04:28 INFO - 'use_talos_json': True, 06:04:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:04:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:04:28 INFO - 'work_dir': 'build'} 06:04:28 INFO - [mozharness: 2017-06-02 13:04:28.423583Z] Running clobber step. 06:04:28 INFO - Running pre-action listener: _resource_record_pre_action 06:04:28 INFO - Running main action method: clobber 06:04:28 INFO - rmtree: /builds/slave/test/build 06:04:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:04:29 INFO - Running post-action listener: _resource_record_post_action 06:04:29 INFO - [mozharness: 2017-06-02 13:04:29.452085Z] Finished clobber step (success) 06:04:29 INFO - [mozharness: 2017-06-02 13:04:29.452187Z] Running read-buildbot-config step. 06:04:29 INFO - Running pre-action listener: _resource_record_pre_action 06:04:29 INFO - Running main action method: read_buildbot_config 06:04:29 INFO - Using buildbot properties: 06:04:29 INFO - { 06:04:29 INFO - "project": "", 06:04:29 INFO - "product": "firefox", 06:04:29 INFO - "who": "cbook@mozilla.com", 06:04:29 INFO - "installer_path": "public/build/target.tar.bz2", 06:04:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 06:04:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 06:04:29 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 06:04:29 INFO - "stage_platform": "linux64-stylo", 06:04:29 INFO - "basedir": "/builds/slave/test", 06:04:29 INFO - "buildnumber": 131, 06:04:29 INFO - "platform": "ubuntu64_hw_stylo", 06:04:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:04:29 INFO - "slavebuilddir": "test", 06:04:29 INFO - "taskId": "XpEEyQNHSnKGwdhgRGL7sQ", 06:04:29 INFO - "branch": "mozilla-central", 06:04:29 INFO - "script_repo_revision": "production", 06:04:29 INFO - "revision": "194c009d6295597344662f8e20f5e309deffe8e9", 06:04:29 INFO - "slavename": "talos-linux64-ix-032", 06:04:29 INFO - "repo_path": "mozilla-central" 06:04:29 INFO - } 06:04:29 INFO - Finding installer, test and symbols from parent task. 06:04:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XpEEyQNHSnKGwdhgRGL7sQ'}, attempt #1 06:04:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XpEEyQNHSnKGwdhgRGL7sQ'}, attempt #1 06:04:30 INFO - Task dependencies: SkoD75AaTneoUY332DUFRA 06:04:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA'}, attempt #1 06:04:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2 06:04:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/test_packages.json 06:04:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.crashreporter-symbols.zip 06:04:30 INFO - Running post-action listener: _resource_record_post_action 06:04:30 INFO - [mozharness: 2017-06-02 13:04:30.824051Z] Finished read-buildbot-config step (success) 06:04:30 INFO - [mozharness: 2017-06-02 13:04:30.824272Z] Running download-and-extract step. 06:04:30 INFO - Running pre-action listener: _resource_record_pre_action 06:04:30 INFO - Running main action method: download_and_extract 06:04:30 INFO - mkdir: /builds/slave/test/build/tests 06:04: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')]} 06:04:30 INFO - trying https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.test_packages.json 06:04:30 INFO - Downloading https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:04:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:04:31 INFO - Downloaded 989 bytes. 06:04:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:04:31 INFO - Using the following test package requirements: 06:04:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:04:31 INFO - u'common': [u'target.common.tests.zip'], 06:04:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:04:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:04:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:04:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:04:31 INFO - u'mozbase': [u'target.common.tests.zip'], 06:04:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:04:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:04:31 INFO - u'web-platform': [u'target.common.tests.zip', 06:04:31 INFO - u'target.web-platform.tests.tar.gz'], 06:04:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:04:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:04:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.common.tests.zip 06:04:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:04:31 INFO - Fetch https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.common.tests.zip into memory 06:04:34 INFO - Content-Length response header: 65101747 06:04:34 INFO - Bytes received: 65101747 06:04:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:04:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.common.tests.zip 06:04:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:04:45 INFO - Fetch https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.common.tests.zip into memory 06:04:46 INFO - Content-Length response header: 65101747 06:04:46 INFO - Bytes received: 65101747 06:05:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.talos.tests.zip 06:05:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:05:01 INFO - Fetch https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.talos.tests.zip into memory 06:05:02 INFO - Content-Length response header: 14447425 06:05:02 INFO - Bytes received: 14447425 06:05:02 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')]} 06:05:02 INFO - trying https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2 06:05:02 INFO - Downloading https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:05:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:05:03 INFO - Downloaded 60354273 bytes. 06:05:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2 06:05:03 INFO - mkdir: /builds/slave/test/properties 06:05:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:05:03 INFO - Writing to file /builds/slave/test/properties/build_url 06:05:03 INFO - Contents: 06:05:03 INFO - build_url:https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2 06:05:03 INFO - Running post-action listener: _resource_record_post_action 06:05:03 INFO - Running post-action listener: set_extra_try_arguments 06:05:03 INFO - [mozharness: 2017-06-02 13:05:03.750052Z] Finished download-and-extract step (success) 06:05:03 INFO - [mozharness: 2017-06-02 13:05:03.750149Z] Running populate-webroot step. 06:05:03 INFO - Running pre-action listener: _resource_record_pre_action 06:05:03 INFO - Running main action method: populate_webroot 06:05:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:05:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:05:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:05:03 INFO - '--tppagecycles', 06:05:03 INFO - '7'], 06:05:03 INFO - 'tests': ['tsvgm']}}, 06:05:03 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:05:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:05:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:05:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:05:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:05:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['damp', 'tps']}, 06:05:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'tests': ['damp', 'tps']}, 06:05:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['dromaeo_dom']}, 06:05:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:05:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:05:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:05:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:05:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['a11yr', 06:05:03 INFO - 'ts_paint', 06:05:03 INFO - 'tpaint', 06:05:03 INFO - 'sessionrestore', 06:05:03 INFO - 'sessionrestore_no_auto_restore', 06:05:03 INFO - 'tabpaint']}, 06:05:03 INFO - 'other-e10s': {'tests': ['a11yr', 06:05:03 INFO - 'ts_paint', 06:05:03 INFO - 'tpaint', 06:05:03 INFO - 'sessionrestore', 06:05:03 INFO - 'sessionrestore_no_auto_restore', 06:05:03 INFO - 'tabpaint']}, 06:05:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:05:03 INFO - 'ts_paint', 06:05:03 INFO - 'tpaint', 06:05:03 INFO - 'sessionrestore', 06:05:03 INFO - 'sessionrestore_no_auto_restore', 06:05:03 INFO - 'tabpaint']}, 06:05:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:05:03 INFO - 'ts_paint', 06:05:03 INFO - 'tpaint', 06:05:03 INFO - 'sessionrestore', 06:05:03 INFO - 'sessionrestore_no_auto_restore', 06:05:03 INFO - 'tabpaint']}, 06:05:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['a11yr', 06:05:03 INFO - 'ts_paint', 06:05:03 INFO - 'tpaint', 06:05:03 INFO - 'sessionrestore', 06:05:03 INFO - 'sessionrestore_no_auto_restore', 06:05:03 INFO - 'tabpaint']}, 06:05:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['a11yr', 06:05:03 INFO - 'ts_paint', 06:05:03 INFO - 'tpaint', 06:05:03 INFO - 'sessionrestore', 06:05:03 INFO - 'sessionrestore_no_auto_restore', 06:05:03 INFO - 'tabpaint']}, 06:05:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 06:05:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 06:05:03 INFO - 'bloom_basic_ref']}, 06:05:03 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 06:05:03 INFO - 'talos_options': ['--mitmproxy', 06:05:03 INFO - 'mitmproxy-recording-1.mp', 06:05:03 INFO - '--firstNonBlankPaint'], 06:05:03 INFO - 'tests': ['Quantum_1']}, 06:05:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['tsvgx', 06:05:03 INFO - 'tsvgr_opacity', 06:05:03 INFO - 'tart', 06:05:03 INFO - 'tscrollx', 06:05:03 INFO - 'cart', 06:05:03 INFO - 'tsvg_static']}, 06:05:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:05:03 INFO - 'tsvgr_opacity', 06:05:03 INFO - 'tart', 06:05:03 INFO - 'tscrollx', 06:05:03 INFO - 'cart', 06:05:03 INFO - 'tsvg_static']}, 06:05:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'talos_options': ['--disable-e10s'], 06:05:03 INFO - 'tests': ['tp5o']}, 06:05:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:05:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'talos_options': ['--disable-e10s', 06:05:03 INFO - '--xperf_path', 06:05:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:05:03 INFO - 'tests': ['tp5n']}, 06:05:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:05:03 INFO - 'talos_options': ['--xperf_path', 06:05:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:05:03 INFO - 'tests': ['tp5n']}}, 06:05:03 INFO - 'talos.zip': {'path': '', 06:05:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:05:03 INFO - Running post-action listener: _resource_record_post_action 06:05:03 INFO - [mozharness: 2017-06-02 13:05:03.764374Z] Finished populate-webroot step (success) 06:05:03 INFO - [mozharness: 2017-06-02 13:05:03.764468Z] Running create-virtualenv step. 06:05:03 INFO - Running pre-action listener: _resource_record_pre_action 06:05:03 INFO - Running main action method: create_virtualenv 06:05:03 INFO - Creating virtualenv /builds/slave/test/build/venv 06:05:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:05:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:05:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:05:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:05:03 INFO - Using real prefix '/usr' 06:05:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:05:04 INFO - Installing distribute.............................................................................................................................................................................................done. 06:05:06 INFO - Installing pip.................done. 06:05:06 INFO - Return code: 0 06:05:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:05:06 INFO - Reading from file tmpfile_stdout 06:05:06 INFO - Output received: 06:05:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:05:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:05:06 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')]} 06:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:06 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')]} 06:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:06 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:06 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 06:05:06 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 06:05:06 INFO - Using env: {'DISPLAY': ':0', 06:05:06 INFO - 'HOME': '/home/cltbld', 06:05:06 INFO - 'LANG': 'en_US.UTF-8', 06:05:06 INFO - 'LANGUAGE': 'en_US:en', 06:05:06 INFO - 'LOGNAME': 'cltbld', 06:05:06 INFO - 'MAIL': '/var/mail/cltbld', 06:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:06 INFO - 'MOZ_NO_REMOTE': '1', 06:05:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:06 INFO - 'NO_EM_RESTART': '1', 06:05:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:06 INFO - 'PWD': '/builds/slave/test', 06:05:06 INFO - 'SHELL': '/bin/bash', 06:05:06 INFO - 'SHLVL': '1', 06:05:06 INFO - 'TERM': 'linux', 06:05:06 INFO - 'TMOUT': '86400', 06:05:06 INFO - 'USER': 'cltbld', 06:05:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:06 INFO - '_': '/tools/buildbot/bin/python'} 06:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:05:06 INFO - Cleaning up... 06:05:06 INFO - Return code: 0 06:05:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:05:06 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')]} 06:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:06 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')]} 06:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:06 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:06 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 06:05:06 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 06:05:06 INFO - Using env: {'DISPLAY': ':0', 06:05:06 INFO - 'HOME': '/home/cltbld', 06:05:06 INFO - 'LANG': 'en_US.UTF-8', 06:05:06 INFO - 'LANGUAGE': 'en_US:en', 06:05:06 INFO - 'LOGNAME': 'cltbld', 06:05:06 INFO - 'MAIL': '/var/mail/cltbld', 06:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:06 INFO - 'MOZ_NO_REMOTE': '1', 06:05:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:06 INFO - 'NO_EM_RESTART': '1', 06:05:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:06 INFO - 'PWD': '/builds/slave/test', 06:05:06 INFO - 'SHELL': '/bin/bash', 06:05:06 INFO - 'SHLVL': '1', 06:05:06 INFO - 'TERM': 'linux', 06:05:06 INFO - 'TMOUT': '86400', 06:05:06 INFO - 'USER': 'cltbld', 06:05:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:06 INFO - '_': '/tools/buildbot/bin/python'} 06:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:07 INFO - Downloading/unpacking psutil>=3.1.1 06:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:05:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:05:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:05:08 INFO - Installing collected packages: psutil 06:05:08 INFO - Running setup.py install for psutil 06:05:08 INFO - building 'psutil._psutil_linux' extension 06:05:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 06:05:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 06:05:08 INFO - building 'psutil._psutil_posix' extension 06:05:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 06:05:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:05:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:05:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:05:08 INFO - Successfully installed psutil 06:05:08 INFO - Cleaning up... 06:05:08 INFO - Return code: 0 06:05:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:05:08 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')]} 06:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:08 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')]} 06:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:08 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 06:05:08 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 06:05:08 INFO - Using env: {'DISPLAY': ':0', 06:05:08 INFO - 'HOME': '/home/cltbld', 06:05:08 INFO - 'LANG': 'en_US.UTF-8', 06:05:08 INFO - 'LANGUAGE': 'en_US:en', 06:05:08 INFO - 'LOGNAME': 'cltbld', 06:05:08 INFO - 'MAIL': '/var/mail/cltbld', 06:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:08 INFO - 'MOZ_NO_REMOTE': '1', 06:05:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:08 INFO - 'NO_EM_RESTART': '1', 06:05:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:08 INFO - 'PWD': '/builds/slave/test', 06:05:08 INFO - 'SHELL': '/bin/bash', 06:05:08 INFO - 'SHLVL': '1', 06:05:08 INFO - 'TERM': 'linux', 06:05:08 INFO - 'TMOUT': '86400', 06:05:08 INFO - 'USER': 'cltbld', 06:05:08 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:08 INFO - '_': '/tools/buildbot/bin/python'} 06:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:05:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:05:10 INFO - Installing collected packages: mozsystemmonitor 06:05:10 INFO - Running setup.py install for mozsystemmonitor 06:05:10 INFO - Successfully installed mozsystemmonitor 06:05:10 INFO - Cleaning up... 06:05:10 INFO - Return code: 0 06:05:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:05:10 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')]} 06:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:10 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')]} 06:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:10 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:10 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 06:05:10 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 06:05:10 INFO - Using env: {'DISPLAY': ':0', 06:05:10 INFO - 'HOME': '/home/cltbld', 06:05:10 INFO - 'LANG': 'en_US.UTF-8', 06:05:10 INFO - 'LANGUAGE': 'en_US:en', 06:05:10 INFO - 'LOGNAME': 'cltbld', 06:05:10 INFO - 'MAIL': '/var/mail/cltbld', 06:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:10 INFO - 'MOZ_NO_REMOTE': '1', 06:05:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:10 INFO - 'NO_EM_RESTART': '1', 06:05:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:10 INFO - 'PWD': '/builds/slave/test', 06:05:10 INFO - 'SHELL': '/bin/bash', 06:05:10 INFO - 'SHLVL': '1', 06:05:10 INFO - 'TERM': 'linux', 06:05:10 INFO - 'TMOUT': '86400', 06:05:10 INFO - 'USER': 'cltbld', 06:05:10 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:10 INFO - '_': '/tools/buildbot/bin/python'} 06:05:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:10 INFO - Downloading/unpacking jsonschema==2.5.1 06:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:05:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:11 INFO - Downloading functools32-3.2.3-2.tar.gz 06:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:05:11 INFO - warning: no files found matching '*.txt' 06:05:11 INFO - no previously-included directories found matching 'build' 06:05:11 INFO - no previously-included directories found matching 'dist' 06:05:11 INFO - no previously-included directories found matching '.git*' 06:05:11 INFO - Installing collected packages: jsonschema, functools32 06:05:11 INFO - Running setup.py install for functools32 06:05:11 INFO - warning: no files found matching '*.txt' 06:05:11 INFO - no previously-included directories found matching 'build' 06:05:11 INFO - no previously-included directories found matching 'dist' 06:05:11 INFO - no previously-included directories found matching '.git*' 06:05:11 INFO - Successfully installed jsonschema functools32 06:05:11 INFO - Cleaning up... 06:05:11 INFO - Return code: 0 06:05:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:05:11 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')]} 06:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:11 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')]} 06:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:11 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:11 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 06:05:11 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 06:05:11 INFO - Using env: {'DISPLAY': ':0', 06:05:11 INFO - 'HOME': '/home/cltbld', 06:05:11 INFO - 'LANG': 'en_US.UTF-8', 06:05:11 INFO - 'LANGUAGE': 'en_US:en', 06:05:11 INFO - 'LOGNAME': 'cltbld', 06:05:11 INFO - 'MAIL': '/var/mail/cltbld', 06:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:11 INFO - 'MOZ_NO_REMOTE': '1', 06:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:11 INFO - 'NO_EM_RESTART': '1', 06:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:11 INFO - 'PWD': '/builds/slave/test', 06:05:11 INFO - 'SHELL': '/bin/bash', 06:05:11 INFO - 'SHLVL': '1', 06:05:11 INFO - 'TERM': 'linux', 06:05:11 INFO - 'TMOUT': '86400', 06:05:11 INFO - 'USER': 'cltbld', 06:05:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:11 INFO - '_': '/tools/buildbot/bin/python'} 06:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:05:12 INFO - Cleaning up... 06:05:12 INFO - Return code: 0 06:05:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:05:12 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')]} 06:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:12 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')]} 06:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:12 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 06:05:12 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 06:05:12 INFO - Using env: {'DISPLAY': ':0', 06:05:12 INFO - 'HOME': '/home/cltbld', 06:05:12 INFO - 'LANG': 'en_US.UTF-8', 06:05:12 INFO - 'LANGUAGE': 'en_US:en', 06:05:12 INFO - 'LOGNAME': 'cltbld', 06:05:12 INFO - 'MAIL': '/var/mail/cltbld', 06:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:12 INFO - 'MOZ_NO_REMOTE': '1', 06:05:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:12 INFO - 'NO_EM_RESTART': '1', 06:05:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:12 INFO - 'PWD': '/builds/slave/test', 06:05:12 INFO - 'SHELL': '/bin/bash', 06:05:12 INFO - 'SHLVL': '1', 06:05:12 INFO - 'TERM': 'linux', 06:05:12 INFO - 'TMOUT': '86400', 06:05:12 INFO - 'USER': 'cltbld', 06:05:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:12 INFO - '_': '/tools/buildbot/bin/python'} 06:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:12 INFO - Downloading/unpacking blobuploader==1.2.4 06:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:13 INFO - Downloading blobuploader-1.2.4.tar.gz 06:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:05:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:05:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:14 INFO - Downloading docopt-0.6.1.tar.gz 06:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:05:14 INFO - Installing collected packages: blobuploader, requests, docopt 06:05:14 INFO - Running setup.py install for blobuploader 06:05:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:05:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:05:14 INFO - Running setup.py install for requests 06:05:14 INFO - Running setup.py install for docopt 06:05:14 INFO - Successfully installed blobuploader requests docopt 06:05:14 INFO - Cleaning up... 06:05:14 INFO - Return code: 0 06:05:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:05:14 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')]} 06:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:14 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')]} 06:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:14 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:14 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 06:05:14 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 06:05:14 INFO - Using env: {'DISPLAY': ':0', 06:05:14 INFO - 'HOME': '/home/cltbld', 06:05:14 INFO - 'LANG': 'en_US.UTF-8', 06:05:14 INFO - 'LANGUAGE': 'en_US:en', 06:05:14 INFO - 'LOGNAME': 'cltbld', 06:05:14 INFO - 'MAIL': '/var/mail/cltbld', 06:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:14 INFO - 'MOZ_NO_REMOTE': '1', 06:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:14 INFO - 'NO_EM_RESTART': '1', 06:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:14 INFO - 'PWD': '/builds/slave/test', 06:05:14 INFO - 'SHELL': '/bin/bash', 06:05:14 INFO - 'SHLVL': '1', 06:05:14 INFO - 'TERM': 'linux', 06:05:14 INFO - 'TMOUT': '86400', 06:05:14 INFO - 'USER': 'cltbld', 06:05:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:14 INFO - '_': '/tools/buildbot/bin/python'} 06:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:05:14 INFO - Running setup.py (path:/tmp/pip-BFERmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:05:14 INFO - Running setup.py (path:/tmp/pip-MeoxP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:05:15 INFO - Running setup.py (path:/tmp/pip-wpKeJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:05:15 INFO - Running setup.py (path:/tmp/pip-UMz8s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:05:15 INFO - Running setup.py (path:/tmp/pip-nFFIo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:05:15 INFO - Running setup.py (path:/tmp/pip-N9JEV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:05:15 INFO - Running setup.py (path:/tmp/pip-EzeR6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:05:15 INFO - Running setup.py (path:/tmp/pip-k09JGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:05:15 INFO - Running setup.py (path:/tmp/pip-HJBnJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:05:15 INFO - Running setup.py (path:/tmp/pip-Z1cAMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:05:15 INFO - Running setup.py (path:/tmp/pip-HkXwFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:05:15 INFO - Running setup.py (path:/tmp/pip-6H7JMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:05:15 INFO - Running setup.py (path:/tmp/pip-uzmEgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:05:15 INFO - Running setup.py (path:/tmp/pip-pgcjzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:05:15 INFO - Running setup.py (path:/tmp/pip-qM7Yb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:05:15 INFO - Running setup.py (path:/tmp/pip-g282tX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:05:16 INFO - Running setup.py (path:/tmp/pip-XnO01o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:05:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:05:16 INFO - Running setup.py install for manifestparser 06:05:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Running setup.py install for mozcrash 06:05:16 INFO - Running setup.py install for mozdebug 06:05:16 INFO - Running setup.py install for mozdevice 06:05:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Running setup.py install for mozfile 06:05:16 INFO - Running setup.py install for mozhttpd 06:05:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Running setup.py install for mozinfo 06:05:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Running setup.py install for mozInstall 06:05:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:05:16 INFO - Running setup.py install for mozleak 06:05:17 INFO - Running setup.py install for mozlog 06:05:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Running setup.py install for moznetwork 06:05:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Running setup.py install for mozprocess 06:05:17 INFO - Running setup.py install for mozprofile 06:05:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Running setup.py install for mozrunner 06:05:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Running setup.py install for mozscreenshot 06:05:17 INFO - Running setup.py install for moztest 06:05:17 INFO - Running setup.py install for mozversion 06:05:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:05:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:05:17 INFO - Cleaning up... 06:05:17 INFO - Return code: 0 06:05:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:05:17 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')]} 06:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:17 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')]} 06:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:17 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:17 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 06:05:17 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 06:05:17 INFO - Using env: {'DISPLAY': ':0', 06:05:17 INFO - 'HOME': '/home/cltbld', 06:05:17 INFO - 'LANG': 'en_US.UTF-8', 06:05:17 INFO - 'LANGUAGE': 'en_US:en', 06:05:17 INFO - 'LOGNAME': 'cltbld', 06:05:17 INFO - 'MAIL': '/var/mail/cltbld', 06:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:17 INFO - 'MOZ_NO_REMOTE': '1', 06:05:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:17 INFO - 'NO_EM_RESTART': '1', 06:05:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:17 INFO - 'PWD': '/builds/slave/test', 06:05:17 INFO - 'SHELL': '/bin/bash', 06:05:17 INFO - 'SHLVL': '1', 06:05:17 INFO - 'TERM': 'linux', 06:05:17 INFO - 'TMOUT': '86400', 06:05:17 INFO - 'USER': 'cltbld', 06:05:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:17 INFO - '_': '/tools/buildbot/bin/python'} 06:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:05:18 INFO - Running setup.py (path:/tmp/pip-Zd0dme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:05:18 INFO - Running setup.py (path:/tmp/pip-YX2El2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:05:18 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)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:05:18 INFO - Running setup.py (path:/tmp/pip-22Hobf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:05:18 INFO - Running setup.py (path:/tmp/pip-cFZxTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:05:18 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)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:05:18 INFO - Running setup.py (path:/tmp/pip-QYArDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:05:18 INFO - Running setup.py (path:/tmp/pip-GFkkSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:05:18 INFO - Running setup.py (path:/tmp/pip-S6IRHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:05:18 INFO - Running setup.py (path:/tmp/pip-vGf5EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:05:18 INFO - Running setup.py (path:/tmp/pip-PgVR5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:05:18 INFO - Running setup.py (path:/tmp/pip-GCqSm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:05:18 INFO - Running setup.py (path:/tmp/pip-74BmgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:05:18 INFO - Running setup.py (path:/tmp/pip-UTkCFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:05:18 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)) 06:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:05:18 INFO - Running setup.py (path:/tmp/pip-QqMgDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:05:19 INFO - Running setup.py (path:/tmp/pip-389_eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:05:19 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)) 06:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:05:19 INFO - Running setup.py (path:/tmp/pip-gASOKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:05:19 INFO - Running setup.py (path:/tmp/pip-Hqc6ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:05:19 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)) 06:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:05:19 INFO - Running setup.py (path:/tmp/pip-15KdSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:05:19 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)) 06:05:19 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)) 06:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:05:19 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)) 06:05:19 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)) 06:05:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:20 INFO - Downloading blessings-1.6.tar.gz 06:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:05:20 INFO - Installing collected packages: blessings 06:05:20 INFO - Running setup.py install for blessings 06:05:20 INFO - Successfully installed blessings 06:05:20 INFO - Cleaning up... 06:05:20 INFO - Return code: 0 06:05:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:05:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:05:20 INFO - Reading from file tmpfile_stdout 06:05:20 INFO - Current package versions: 06:05:20 INFO - argparse == 1.2.1 06:05:20 INFO - blessings == 1.6 06:05:20 INFO - blobuploader == 1.2.4 06:05:20 INFO - docopt == 0.6.1 06:05:20 INFO - functools32 == 3.2.3-2 06:05:20 INFO - jsonschema == 2.5.1 06:05:20 INFO - manifestparser == 1.1 06:05:20 INFO - mozInstall == 1.12 06:05:20 INFO - mozcrash == 1.0 06:05:20 INFO - mozdebug == 0.1 06:05:20 INFO - mozdevice == 0.51 06:05:20 INFO - mozfile == 1.2 06:05:20 INFO - mozhttpd == 0.7 06:05:20 INFO - mozinfo == 0.9 06:05:20 INFO - mozleak == 0.1 06:05:20 INFO - mozlog == 3.4 06:05:20 INFO - moznetwork == 0.27 06:05:20 INFO - mozprocess == 0.25 06:05:20 INFO - mozprofile == 0.28 06:05:20 INFO - mozrunner == 6.13 06:05:20 INFO - mozscreenshot == 0.1 06:05:20 INFO - mozsystemmonitor == 0.3 06:05:20 INFO - moztest == 0.8 06:05:20 INFO - mozversion == 1.4 06:05:20 INFO - psutil == 3.1.1 06:05:20 INFO - requests == 1.2.3 06:05:20 INFO - wsgiref == 0.1.2 06:05:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:05:20 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')]} 06:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:20 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')]} 06:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:20 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:20 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 06:05:20 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 06:05:20 INFO - Using env: {'DISPLAY': ':0', 06:05:20 INFO - 'HOME': '/home/cltbld', 06:05:20 INFO - 'LANG': 'en_US.UTF-8', 06:05:20 INFO - 'LANGUAGE': 'en_US:en', 06:05:20 INFO - 'LOGNAME': 'cltbld', 06:05:20 INFO - 'MAIL': '/var/mail/cltbld', 06:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:20 INFO - 'MOZ_NO_REMOTE': '1', 06:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:20 INFO - 'NO_EM_RESTART': '1', 06:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:20 INFO - 'PWD': '/builds/slave/test', 06:05:20 INFO - 'SHELL': '/bin/bash', 06:05:20 INFO - 'SHLVL': '1', 06:05:20 INFO - 'TERM': 'linux', 06:05:20 INFO - 'TMOUT': '86400', 06:05:20 INFO - 'USER': 'cltbld', 06:05:20 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:20 INFO - '_': '/tools/buildbot/bin/python'} 06:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:05:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:05:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 06:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 06:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:05:22 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)) 06:05:22 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)) 06:05:22 INFO - Installing collected packages: marionette-driver 06:05:22 INFO - Running setup.py install for marionette-driver 06:05:22 INFO - Successfully installed marionette-driver 06:05:22 INFO - Cleaning up... 06:05:22 INFO - Return code: 0 06:05:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:05:22 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')]} 06:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:05:22 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')]} 06:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:05:22 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 0x19133b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aeda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c91dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, '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': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 06:05:22 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 06:05:22 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 06:05:22 INFO - Using env: {'DISPLAY': ':0', 06:05:22 INFO - 'HOME': '/home/cltbld', 06:05:22 INFO - 'LANG': 'en_US.UTF-8', 06:05:22 INFO - 'LANGUAGE': 'en_US:en', 06:05:22 INFO - 'LOGNAME': 'cltbld', 06:05:22 INFO - 'MAIL': '/var/mail/cltbld', 06:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:22 INFO - 'MOZ_NO_REMOTE': '1', 06:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:22 INFO - 'NO_EM_RESTART': '1', 06:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:22 INFO - 'PWD': '/builds/slave/test', 06:05:22 INFO - 'SHELL': '/bin/bash', 06:05:22 INFO - 'SHLVL': '1', 06:05:22 INFO - 'TERM': 'linux', 06:05:22 INFO - 'TMOUT': '86400', 06:05:22 INFO - 'USER': 'cltbld', 06:05:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:22 INFO - '_': '/tools/buildbot/bin/python'} 06:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:05:22 INFO - Cleaning up... 06:05:22 INFO - Return code: 0 06:05:22 INFO - Running post-action listener: _resource_record_post_action 06:05:22 INFO - Running post-action listener: _start_resource_monitoring 06:05:22 INFO - Starting resource monitoring. 06:05:22 INFO - [mozharness: 2017-06-02 13:05:22.877140Z] Finished create-virtualenv step (success) 06:05:22 INFO - [mozharness: 2017-06-02 13:05:22.877715Z] Running install step. 06:05:22 INFO - Running pre-action listener: _resource_record_pre_action 06:05:22 INFO - Running main action method: install 06:05:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:05:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:05:23 INFO - Reading from file tmpfile_stdout 06:05:23 INFO - Detecting whether we're running mozinstall >=1.0... 06:05:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:05:23 INFO - Reading from file tmpfile_stdout 06:05:23 INFO - Output received: 06:05:23 INFO - Usage: mozinstall [options] installer 06:05:23 INFO - Options: 06:05:23 INFO - -h, --help show this help message and exit 06:05:23 INFO - -d DEST, --destination=DEST 06:05:23 INFO - Directory to install application into. [default: 06:05:23 INFO - "/builds/slave/test"] 06:05:23 INFO - --app=APP Application being installed. [default: firefox] 06:05:23 INFO - mkdir: /builds/slave/test/build/application 06:05:23 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'] 06:05:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:05:40 INFO - Reading from file tmpfile_stdout 06:05:40 INFO - Output received: 06:05:40 INFO - /builds/slave/test/build/application/firefox/firefox 06:05:40 INFO - Running post-action listener: _resource_record_post_action 06:05:40 INFO - [mozharness: 2017-06-02 13:05:40.594814Z] Finished install step (success) 06:05:40 INFO - [mozharness: 2017-06-02 13:05:40.594968Z] Running setup-mitmproxy step. 06:05:40 INFO - Running pre-action listener: _resource_record_pre_action 06:05:40 INFO - Running main action method: setup_mitmproxy 06:05:40 INFO - Skipping: mitmproxy is not required 06:05:40 INFO - Running post-action listener: _resource_record_post_action 06:05:40 INFO - [mozharness: 2017-06-02 13:05:40.595495Z] Finished setup-mitmproxy step (success) 06:05:40 INFO - [mozharness: 2017-06-02 13:05:40.595598Z] Running run-tests step. 06:05:40 INFO - Running pre-action listener: _resource_record_pre_action 06:05:40 INFO - Running main action method: run_tests 06:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:05:40 INFO - Python 2.7.3 06:05:40 INFO - Return code: 0 06:05:40 INFO - grabbing minidump binary from tooltool 06:05:40 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')]} 06:05:40 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 0x1c8e840>, '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 0x1c8f740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fbd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:05:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:05:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:05:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:05:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:05:40 INFO - Return code: 0 06:05:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:05:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:05:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:05:40 INFO - ENV: RUST_BACKTRACE is now 1 06:05:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:05:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:05:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 06:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 06:05:40 INFO - Using env: {'DISPLAY': ':0', 06:05:40 INFO - 'HOME': '/home/cltbld', 06:05:40 INFO - 'LANG': 'en_US.UTF-8', 06:05:40 INFO - 'LANGUAGE': 'en_US:en', 06:05:40 INFO - 'LOGNAME': 'cltbld', 06:05:40 INFO - 'MAIL': '/var/mail/cltbld', 06:05:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:05:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:05:40 INFO - 'MOZ_NO_REMOTE': '1', 06:05:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:05:40 INFO - 'NO_EM_RESTART': '1', 06:05:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:05:40 INFO - 'PWD': '/builds/slave/test', 06:05:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:05:40 INFO - 'RUST_BACKTRACE': '1', 06:05:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:05:40 INFO - 'SHELL': '/bin/bash', 06:05:40 INFO - 'SHLVL': '1', 06:05:40 INFO - 'TERM': 'linux', 06:05:40 INFO - 'TMOUT': '86400', 06:05:40 INFO - 'USER': 'cltbld', 06:05:40 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1496406752.244137-24443858', 06:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:05:40 INFO - '_': '/tools/buildbot/bin/python'} 06:05:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 06:05:40 INFO - mozversion application_buildid: 20170602121725 06:05:40 INFO - mozversion application_changeset: 194c009d6295597344662f8e20f5e309deffe8e9 06:05:40 INFO - mozversion application_display_name: Nightly 06:05:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:05:40 INFO - mozversion application_name: Firefox 06:05:40 INFO - mozversion application_remotingname: firefox 06:05:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:05:40 INFO - mozversion application_vendor: Mozilla 06:05:40 INFO - mozversion application_version: 55.0a1 06:05:40 INFO - mozversion platform_buildid: 20170602121725 06:05:40 INFO - mozversion platform_changeset: 194c009d6295597344662f8e20f5e309deffe8e9 06:05:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:05:40 INFO - mozversion platform_version: 55.0a1 06:05:40 INFO - using testdate: 1496408740 06:05:40 INFO - actual date: 1496408740 06:05:40 INFO - starting webserver on 'localhost:42271' 06:05:40 INFO - SUITE-START | Running 2 tests 06:05:40 INFO - TEST-START | dromaeo_css 06:05:40 INFO - operating with platform_type : linux_ 06:05:40 INFO - Initialising browser for dromaeo_css test... 06:05:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42271/getInfo.html -profile /tmp/tmpESn_Vv/profile 06:05:40 INFO - TEST-INFO | started process 8638 (/builds/slave/test/build/application/firefox/firefox http://localhost:42271/getInfo.html) 06:06:16 INFO - TEST-INFO | 8638: killed by SIGTERM 06:06:16 INFO - Browser initialized. 06:06:16 INFO - Running cycle 1/1 for dromaeo_css test... 06:06:16 INFO - TEST-INFO | started process 8869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpESn_Vv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:06:28 INFO - PID 8869 | RSS: Main: 192356352 06:06:28 INFO - PID 8869 | 06:09:29 INFO - PID 8869 | Cycle 1(1): loaded http://localhost:42271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42271/tests/dromaeo/cssquery-ext.html) 06:09:30 INFO - PID 8869 | RSS: Main: 188776448 06:09:30 INFO - PID 8869 | 06:12:32 INFO - PID 8869 | Cycle 1(1): loaded http://localhost:42271/tests/dromaeo/cssquery-ext.html (next: http://localhost:42271/tests/dromaeo/cssquery-jquery.html) 06:12:33 INFO - PID 8869 | RSS: Main: 194469888 06:12:33 INFO - PID 8869 | 06:15:35 INFO - PID 8869 | Cycle 1(1): loaded http://localhost:42271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42271/tests/dromaeo/cssquery-mootools.html) 06:15:35 INFO - PID 8869 | RSS: Main: 189448192 06:15:35 INFO - PID 8869 | 06:18:37 INFO - PID 8869 | Cycle 1(1): loaded http://localhost:42271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42271/tests/dromaeo/cssquery-prototype.html) 06:18:38 INFO - PID 8869 | RSS: Main: 195543040 06:18:38 INFO - PID 8869 | 06:21:41 INFO - PID 8869 | Cycle 1(1): loaded http://localhost:42271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42271/tests/dromaeo/cssquery-yui.html) 06:21:41 INFO - PID 8869 | RSS: Main: 194273280 06:21:41 INFO - PID 8869 | 06:24:43 INFO - PID 8869 | Cycle 1(1): loaded http://localhost:42271/tests/dromaeo/cssquery-yui.html (next: http://localhost:42271/tests/dromaeo/cssquery-dojo.html) 06:24:44 INFO - PID 8869 | RSS: Main: 191651840 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | __start_tp_report 06:24:44 INFO - PID 8869 | _x_x_mozilla_page_load 06:24:44 INFO - PID 8869 | _x_x_mozilla_page_load_details 06:24:44 INFO - PID 8869 | |i|pagename|runs| 06:24:44 INFO - PID 8869 | |0;dojo.html;3095.241665791881;3108.70467305606;3111.113332700178;3119.890803821865;3125.9843700781507;5835.387284335142;5847.649141051535;5852.356240813518;5857.619254748438;5884.941150588493;5847.532197424206;5867.383924687911;5883.588148829573;5900.970495147529;5901.321348044966;5416;5508.586855985797;5525.060739674265;5555.30558680165;5574.526165275956;5405.459454054602;5577.191307260445;5585.273914391123;5614.9438505615035;5621.831345059655;5580.330360356763;5605.803796867111;5607.243022192013;5618.634788738719;5653;5429.728513574345;5477.315335583052;5485.259489968843;5493.587980901449;5527.336719593634;5527.2538207341895;5543.528800052;5556.361018482846;5564.693941833198;5568.610197286192;5902.32123305821;5916.556258280603;5931.258592675916;5932;5952.672603006833;6303.243610766692;6368.267649220353;6441.194850643669;6448.193975753031;6454.903176452357;6281.089242059876;6354.936450635527;6377.330380310047;6386.680665966683;6403.135576697133;6060.181875446847;6085.9087113692885;6087.465756144067;6088.634681919099;6102.298235702907;6683.665816709243;6687.130673012477;6689.565178263398;6699.732010719516;6699.832504187434;6175.042868355412;6264.060390941395;6264.874702506016;6271.090691849657;6314.463270622048;6197.938020619827;6229.626222426669;6296.307406185316;6358.936410635834;6484.25431075423;6430.839229019219;6440.194975628046;6443.355664433613;6445.967770161213;6472.126262954441;6437.0988061671405;6447.806565803034;6462.030695395728;6466.321036291216;6474.093626892239;5234.0840352938085;5276.973615131899;5303.496167864047;5307.893842123136;5361.597880159004;4534.478534968521;4581.816727330936;4596.7931443085145;4629.074185162981;4651.813927442932;5608.784530110665;5619.634723742944;5621.15682647598;5632.464915832989;5667.6316039458125;6332.905006424908;6406.487481001509;6433;6439.903401448982;6448.097266382711;5625.46558076982;5706.743196556165;5707.828765137053;5730.570207234474;5736.741846616912;5743.052396354555;5743.483086522233;5744.540436765049;5758.452946970031;5764.942350576524;6152.538559607958;6245.125682404376;6326.778562750282;6333.429991300805;6356.0465930109;6286.245650521967;6382.93617063842;6388.9041664374145;6395.616263024047;6396.520260980538;6347.143135676624;6354.046892966092;6366.554341196072;6367.204099487564;6368.745250189941;6360.363963603602;6367.936320636734;6389.808305750835;6425.1968503937005;6428.4214420702365;6341.0805433212845;6344.96827515878;6436.002419624966;6437.163168788027;6464.127342808661;6325.652089135142;6332.0818481320875;6338.873222535616;6341.619502829845;6343.080253363329;6132.845047373448;6253.249610046823;6289.308176100534;6325.272593651789;6357.14178585885;6105.389461053859;6108;6178.567500274938;6179.165812615419;6183.288921773874;6373.330800265905;6405.135306733531;6406.0390941359165;6414.294427613052;6422.903656445251;6166.136740856194;6198.163247961467;6204.472619827366;6206.875862482815;6219.813405597839 06:24:44 INFO - PID 8869 | |1;ext.html;708.1290013283661;744.1814004594945;768.3123604374085;1132.8130858408363;1156.2773266708307;36070.458943115875;37139.25721485572;37163;37223.44164837523;37373.252534949315;39425.40861887067;39524.802375988154;39559.802200988954;39608.40587391184;39668.40497392541;7135.179454362763;7230.710771569131;7309.744158954438;7403.8149046274;7421.703131874719;7243;7303.744368947088;7339.045924029869;7358.2641735826255;7361.889571656429;7251.891221631679;7296.744613938513;7301.525400848955;7345.265473452639;7363.926360736405;7269.236730143338;7308.744193953239;7328.303811137934;7332.450066245053;7348.963255183743;7202.459815513805;7262.564246145248;7264.34620884123;7299.160596531414;7302.415806735448;34797;34997.30005399903;35179.1205219869;35208.64791352105;35238;3462.8441720122655;3488.215151590897;3499.0202743231935;3505.193805424741;3510.543629328171;28279.434411311864;28497;28578.285542861595;28591;28616.42767144666;3640.10817349751;3697.5008373869723;3704;3712.962870371289;3734.869279575229;139252.30373848067;141280;150947.24526377406;165026;166530.99999999878;41879.58120418818;42221.15557688829;42255.15489690189;42265.57734422616;42337.78831105825;44201.77899110484;44306.33540496895;44318.23295019895;44373.55626443759;44414.33378499325;14447.566573002827;14509.85490145106;14525.201113938834;14538.345774440175;14551;11778.705532361759;11812.291262524275;11815.527378904919;11832.408379581158;11837.46731397072;13381.732365352833;13407.865921340857;13434.798478022838;13437.865621343857;13531.120477168952;5380.192971054373;5453.536449401766;5454.563634909197;5455.536279416294;5618.859528511738;971.8736564246602;1006.5571148694551;1012.8531362952479;1026.7381817636456;1057.7672911959357;22672.659910101363;23119.306420807403;23175.88412057929;23279.3016209514;23293.301200963997;1100.6753007862699;1125.876153623101;1128.1933417606451;1134.846795682589;1148.735790768128;11731.530738770523;12193.939030304791;12240.837120473541;12281.392116916324;12281.508739650493;200872;200903;201470;201509;202135;48380.27429588559;48433;48446;48593.75703121462;48636.27045594319;1439.1221354973459;1461.1890400827642;1468.8678018978344;1472.1535117307592;1477.2170749502995;21632.567348652625;21783.673244901023;22539.5492090154;22632.094716211166;23012.53974920459;34294;34496.310073798886;34527.65472345244;34607.82696086504;34646;167297;167636;168765;169337;169577;31731.36537269288;31907;33333.49999749957;33422.498662519596;33462;40773.1845363097;41013.38479922744;41797.58202417937;42454.78772606117;42723.78638106914;30428;30550.694493054783;30854;31372;31774.205644859063;29930.85034574813;29974.400511990076;30045;31496;31742;33944.15139621529;33957.32085358328;34006.82996585001;34024.82987585046;34343.141421464665;28548.85725571359;28764.71235287704;29348.26629334198;29943.471763370824;31623.20941976469;21884.343469695934;24159;24264.636030459205;24437.51124977526;24699.87650061738 06:24:44 INFO - PID 8869 | |2;jquery.html;411.4281149202609;413.4088253797069;421.6668831623018;431.16354272710936;438.53296239504925;19789.208431662733;20594.794052059493;20598.691019634698;22118.447038823997;23108;22682.432939176488;23290;23331.183408580702;23347.883260583716;23363;6037.849053773658;6194.752209911598;6196.876062478747;6198.8450288742915;6211.782587609435;6100.633961962274;6132;6158.938410615881;6159.9384006160035;6161.260648722159;5616;5789.913151302734;5834.649921004753;5841.5326773858205;5843.386444423338;5742;5803.6517808931385;5808.2449281593545;5836.9124463133085;5857.355690874;5238.292830467876;5252.763625636855;5254.238135470336;5281.339832520935;5286.365636123652;25209;25700.357491062685;26107.08625198128;26196.60705089425;26211.213663590122;1884.9623007539772;1916.290972340229;1921.702136168896;1927.5181204698824;1931.8357939575153;16734.246958886757;17140.828591714173;17146.914265428717;17244.655106897793;17276.481705548853;2193.824494040473;2205.5368372641765;2237.7202849643795;2243.2148747938254;2290.347251033464;118774;121986.39006804998;122557;128847;129649.35175324157;19407;20111.698324525143;20243;20531.28140515075;20800.47998800042;39652;39763.6200922919;39789;39874.40188397176;40022.199556008716;10746.086582640562;10850.565977360817;10959.671209863716;10997.835032474359;11003.029182120943;8922.152395522415;8946.060663630225;8954.23888969432;9009.909900901039;9066.592003359732;10712.892871071346;10740.946295268473;10964.945175274232;10995.175361847894;11083.445827708743;4227.260229459832;4268.637165840876;4363.47638283408;4367.890802729958;4426.1369033038245;920.1810388754014;965.7199412170532;994.5275993902839;998.1665309466676;1009.7677534167183;19299.324523641884;19831.801681983285;20065.699014514794;20645.690314645293;20893.791062089487;985.5909797433984;1002.3885429887763;1025.3130402630256;1038.787048655024;1062.5749700120014;10628.627998020187;11280.71798205035;11322.830157547643;11367.261128026652;11369.943150284196;454843;462180;465133;466232;472708;97105.51447242718;100529.49735251423;100593.49703251584;101130;101496;1171.5489536528328;1204.8855358740905;1230.6615680687703;1231.4581584102966;1239.114033466076;19819.108140134016;20497.48756281105;21119.260825871635;21218.78781212168;21350.572988539832;47124.764376177896;48524.2721359187;48673;48881.755591221816;49214;60561.69719151376;60962.695186525554;61016.38983610107;61085;61323;9955;9991.200703943812;10018.098371146632;10109.949450252701;10187.898121018992;16185.30054344324;16191.271392787588;16336;16373.672526549164;16433;14381.7842732357;14388.273407191204;14403.207823569805;14446.711065778836;14522.782158267846;13022.413991370222;13156.473741050251;13387.196768193939;13546.32268386557;13745.518906838613;13977.510787122403;14080.366383512972;14947.177905215318;15018.549443516711;15143.621409464851;10034.066890463757;10043.296969212084;10066.54700538463;10159;10162.898371016196;14326.283685815459;14333.928330358282;14369.425222991382;14381.339452201377;14402.855971440571 06:24:44 INFO - PID 8869 | |3;mootools.html;1305.3864683598706;1308.2608326295644;1363.6795353092025;1373.9862601373984;1384.3147641917246;10409.06318431341;10720.195985301114;10723.78552428951;10775.78448431032;10798.51406686699;10474.26680132389;10526.7893742314;10534.683959481208;10554.419506927115;10585.364878107299;2152.1068756466125;2231.4309850987975;2244.820414366851;2282.2696737044153;2294.4149241943283;2243.0354947372625;2253.43664083979;2255.793150664395;2256.17649557911;2257.7064981552458;2273.59075366434;2287.714035745532;2298.2071185440996;2303.7581053989343;2305.5158416732424;2274.2267629006133;2276.0440614941763;2286.1084177170915;2293.71328583927;2322.523882604079;2283.9265545193734;2313.1903833658084;2313.421644588853;2313.456337760625;2323.384303159647;10481.947590262076;10667.293233082748;10686.51910664022;10727.678169654922;10742.624008159755;6027.457528822427;6204.81385558434;6207.782727604557;6220.409061139185;6223.564350495467;3448.499967504698;3565.1978304881454;3636.454506834611;3662.8168591570316;3686.8340924658455;1240.6712221261373;1244.1104610203747;1272.1921579796804;1288.2206265209506;1305.9542915997895;15721.37114515418;15919.124448155348;16001.359945602162;16017.439389621424;16595.751063734053;5019.548240658358;5044.041632089891;5176.456472070454;5180.740962951837;5234.450382709761;12143.696407589881;12184.147109702235;12184.756304873852;12185.51257949672;12194.329311887754;5940.70296485174;6099.176611157525;6134.969325153345;6267.122602835607;6276.556392029859;5659.490645841812;5760;5773.971130144407;5792.681402522901;5806.071028635398;4174.186033723395;4179.811908464127;4214.726042807208;4224.38746381779;4232.724872883253;1230.8584512781144;1231.612042206702;1233.2353940556732;1239.7892358299111;1243.0925424440209;797.3740613618397;826.7519744076753;838.8196537744415;850.8511010573125;870.0299166429386;6022.849428764228;6071.210742603522;6098.207233059674;6116.29662588808;6227.19046523949;599.6462087368474;607.5807692692028;610.215872603711;616.3990109643152;627.9309275979639;3989.900252493711;4033.5361433435532;4044.6764258859807;4056.716029877939;4078.8164532596106;13824.170549767046;14270.571882843531;14339.78490322646;14477.493287734878;14542.927285363718;14130.646733831736;14210.431582736575;14273.643158920902;14273.714525709427;14333.56999290002;7852.214778522102;8350.832983340299;8607.870881936777;8611.526366049928;8767.21095101444;5618.67961029166;6666.666666666744;6693.497987650849;7171.816650252858;7185.137783466018;11942;11948.462319195483;12049.216800907912;12091.57679481214;12154.696132596755;12249.877501224873;12483.188592741442;12546.30254495288;12693.111482196158;12746.936265318614;9304.627814887328;9438.197753191056;9484.668036618685;9498.477583732962;9541.522923853918;10408.687739367831;10415.895841041493;10498.47507624631;10504.10715089226;10538.89461105379;8733.825093765692;8898.510581917797;8935.865962010444;9067.455952642862;9233.122853328923;8767.24655068976;8900.020997690379;8900.86648700283;8905.688300909436;9244.95377523108;7613.200613935457;7679.616019199129;7690.115636701851;7738.535687858746;7820.530768153929;5846.473817356288;5849.502792262705;5880.558958078247;5945.464908158287;6111.1444397785235;5786.768529258951;5947.524198064231;5975.34271230173;6172.567920245539;6182.536309776695 06:24:44 INFO - PID 8869 | |4;prototype.html;400.9959900400996;403.92931237033514;407.3218091877025;408.51998901290995;411.3562275039563;6192.752289908398;6240.162300185388;6254.186955695754;6267.811965641033;6272.090546870707;5838.766449342022;6043.214382130328;6048.879022419549;6071.6964151792345;6103.511719062475;4720.0175926111115;4722.97638511807;4745.240761478164;4761.8095276188915;4761.857144285677;4715.2927060940965;4718.292256161586;4731.57415832575;4742.099001189763;4779.52204779521;4661.74360410179;4672.6261899047995;4672.953270467286;4678.7894544745395;4679.227927391977;4634.698744581591;4660.252405347995;4660.440747110351;4664.3936288282475;4699.600533954618;4095.385692146172;4097.364908439196;4098.815553300079;4099.97950010251;4106.2198182345255;5970.313413957364;5980.8504787380225;5985;5986.640801551877;5994.294478834138;5349.224362467459;5357.142857142839;5365.463453654604;5370.409254981949;5379.193121031837;6436.207413327996;6438.839029024265;6442.355764423567;6445.613263204176;6459.06343580183;4442.155990361821;4466.352378905073;4475.149721552894;4478.328250762379;4495.280755079204;6473.838154046187;6662.533622646368;6781.779279729647;6837.589744615339;6843.520953533254;4738.549837765476;4967.105920934267;4974.77613507393;4982.825601103944;5003.774830132653;5041.344625198701;5065.594752419797;5065.721385323769;5080.542751152414;5099.388073431138;5618.43815618443;5628.380878103405;5636.746346414421;5648.983183027012;5667.801626943037;6370.674703733104;6536.673166341664;6544.018397240452;6549.6070235786;6579.80260592183;1738.4089409600545;1762.6739053275185;1764.5323989142887;1767.1959258537593;1773.9024353660673;1222.1811386371153;1248.2073883084306;1255.8618551959278;1272.8281681973;1278.2713853103828;5737;5769.307683078057;5803.245578074823;5807.767689292466;5822;6591;6632.701528431136;6682.665866706645;6686.465082793365;6707.27137368164;5690.857728556819;5714.885702285931;5718.999175144333;5772.797952071742;5789.9710501447225;3554.182538016271;3563.483294922222;3575.0515968797067;3579.319929213441;3579.4451859961746;6439.61362318253;6500.934990650033;6512.446442052478;6513.869722605521;6515.837104072436;6819.488538359643;6824.53272546395;6855.280195579492;6878.415334696607;6884.621345825927;6476.514261430318;6506.41442270198;6530.510211734139;6542.149520562297;6554.279029306677;6002.532754876576;6642.701078451578;6711.865762684622;6723.630200339028;6753.763618273337;6682.498812589172;6695.330466953461;6703.698333574904;6714;6717.361850624183;6268.4671802897265;6378.1070650108095;6523.836904077436;6528.869422611426;6532.804015879531;4581.198290299251;4586.449626044896;4593.954060459487;4602.585767280961;4616.6306695464955;6678.565893216925;6717.16035495563;6742.258351581224;6747.122874026345;6750.831229219309;6429.6142231466265;6432.64914367988;6512.251091124392;6515.218173819172;6549.770758023446;6475.708593113227;6494.447971922439;6521.184851893513;6527.706253218712;6538.673066346759;6393.29673735898;6399.680015999088;6417.711202995783;6420.6147631142285;6442.420182183626;6420.903686444801;6586.473082153512;6593.934060659332;6599.571027883172;6633.502487313567;6271;6282.340354262737;6300.432961033529;6333.303336633059;6397.744090236339 06:24:44 INFO - PID 8869 | |5;yui.html;820.9753707388772;850.3707256630092;853.4879072556467;864.8745931839883;877.679646928871;13648.658783530416;14318;16890.746638800414;17492.30030798768;17541.035243061626;16663.583410414714;17155.656886862256;17175.05537195454;17197.398091066814;17290.740638890427;919.9908000919972;936.283742936654;940.6378544260441;962.4754508792724;1009.6617633092923;1022.9386236825777;1028.953697083633;1032.1536340201037;1033.6020632056675;1039.7920415916847;993.2203220471931;1019.6329321444274;1022.2077889635517;1030.4435604773414;1033.5555711044249;1020.3214862116683;1025.3437799808128;1025.661531694539;1025.7281820317585;1027.1525991057333;743.5724458436421;753.5855279596256;754.2231501553409;754.7660225330166;757.2087168908504;15734.055956642638;16692.081935493545;16791;16863.24115414797;16933.661326773396;18243.45269641913;18548.90725546377;18737.62524749511;18764.0617969101;18791.718124228148;14231.07498012626;14772;14884.255787210595;14886.10683358991;14899.77650335246;3393.6606339366112;3469.826508674556;3470.7396945228957;3481.9651803481893;3506.947395789065;84750;86439.56780216121;86548.5672571633;86600;87470;14633.122012679274;14820.110793352433;15055.397784088515;15145.167015814015;15168.469103581321;25899;26234.344141396617;26329.341766455993;26754.464910701678;27056;8083.585372559778;8253.050899146554;8261.1325810789;8275.296599788964;8305.086440491541;3261.2336101016244;3477.8434970425888;3860.613938606173;3876.3022655922205;3900.31744444721;4157.542670306264;4240.1307731914885;4261.083866968616;4261.74429534229;4279.572042795696;801.2908575910245;834.4367551902442;837.3385025829648;841.2008591837656;843.2200214801406;659.6536818170403;685.1093578348227;712.1596516110935;716.7097325583147;722.8048426924701;11380.94309528447;12350.320732359627;12671.429785659668;12750.872491275153;13077.869221307856;650.9707063182168;659.7855696898528;675.1021141881287;680.1226417920925;707.4729326651677;5357.196420536873;5628.183913332544;5683.857903552362;5703.486686198262;5766.884662306814;94660;94696.52651736834;96075.51962240144;97847.51076244573;98268.01731982731;69790.65104674445;79337.60331198307;79560.60219698865;80693.59653201814;83206.16793831984;64794.352056478834;65164;65324.673376633764;65952.34047659462;66083.33916660772;10991.505382257978;12093.153479256367;12096.153269271066;12198.634040978784;12235.632931012084;27625.309367265978;28127.015554455495;28674;29854.402911942074;30081.69918300789;53097.734511327195;53837.46162538324;54666;56327;56378;16638.334466621684;16966.83033169702;17100.572485687957;17194.22625981809;17275.654486910444;18424.81575184231;18894.716579251046;19037.14332854997;19039.714404284223;19493.415197544095;16896.23966921516;16944.830551694326;16945.576360591083;17056.744148838025;17388.391406300718;17100.572485687957;17101;17156.656866862344;17234;17406.564835879206;16748.497545073667;16906.492805215865;17455.912720436318;17669.29322827124;17849.196786144916;10955.452227388758;10985.505652245827;11126.61056863006;11429.942850285695;11684.88315116838;13244.20534767917;13834.723305533631;13858.930705346813;13865.72268554603;13890.166590004632 06:24:44 INFO - PID 8869 | __end_tp_report 06:24:44 INFO - PID 8869 | __start_cc_report 06:24:44 INFO - PID 8869 | _x_x_mozilla_cycle_collect,390 06:24:44 INFO - PID 8869 | __end_cc_report 06:24:44 INFO - PID 8869 | __startTimestamp1496409884099__endTimestamp 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | ------- Summary: start ------- 06:24:44 INFO - PID 8869 | Number of tests: 6 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | [#0] dojo.html Cycles:165 Average:5941.82 Median:6179.17 stddev:669.84 (10.8%) stddev-sans-first:633.58 06:24:44 INFO - PID 8869 | Values: 3095.2 3108.7 3111.1 3119.9 3126.0 5835.4 5847.6 5852.4 5857.6 5884.9 5847.5 5867.4 5883.6 5901.0 5901.3 5416.0 5508.6 5525.1 5555.3 5574.5 5405.5 5577.2 5585.3 5614.9 5621.8 5580.3 5605.8 5607.2 5618.6 5653.0 5429.7 5477.3 5485.3 5493.6 5527.3 5527.3 5543.5 5556.4 5564.7 5568.6 5902.3 5916.6 5931.3 5932.0 5952.7 6303.2 6368.3 6441.2 6448.2 6454.9 6281.1 6354.9 6377.3 6386.7 6403.1 6060.2 6085.9 6087.5 6088.6 6102.3 6683.7 6687.1 6689.6 6699.7 6699.8 6175.0 6264.1 6264.9 6271.1 6314.5 6197.9 6229.6 6296.3 6358.9 6484.3 6430.8 6440.2 6443.4 6446.0 6472.1 6437.1 6447.8 6462.0 6466.3 6474.1 5234.1 5277.0 5303.5 5307.9 5361.6 4534.5 4581.8 4596.8 4629.1 4651.8 5608.8 5619.6 5621.2 5632.5 5667.6 6332.9 6406.5 6433.0 6439.9 6448.1 5625.5 5706.7 5707.8 5730.6 5736.7 5743.1 5743.5 5744.5 5758.5 5764.9 6152.5 6245.1 6326.8 6333.4 6356.0 6286.2 6382.9 6388.9 6395.6 6396.5 6347.1 6354.0 6366.6 6367.2 6368.7 6360.4 6367.9 6389.8 6425.2 6428.4 6341.1 6345.0 6436.0 6437.2 6464.1 6325.7 6332.1 6338.9 6341.6 6343.1 6132.8 6253.2 6289.3 6325.3 6357.1 6105.4 6108.0 6178.6 6179.2 6183.3 6373.3 6405.1 6406.0 6414.3 6422.9 6166.1 6198.2 6204.5 6206.9 6219.8 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | [#1] ext.html Cycles:180 Average:33526.69 Median:33703.08 stddev:45324.26 (134.5%) stddev-sans-first:45384.41 06:24:44 INFO - PID 8869 | Values: 708.1 744.2 768.3 1132.8 1156.3 36070.5 37139.3 37163.0 37223.4 37373.3 39425.4 39524.8 39559.8 39608.4 39668.4 7135.2 7230.7 7309.7 7403.8 7421.7 7243.0 7303.7 7339.0 7358.3 7361.9 7251.9 7296.7 7301.5 7345.3 7363.9 7269.2 7308.7 7328.3 7332.5 7349.0 7202.5 7262.6 7264.3 7299.2 7302.4 34797.0 34997.3 35179.1 35208.6 35238.0 3462.8 3488.2 3499.0 3505.2 3510.5 28279.4 28497.0 28578.3 28591.0 28616.4 3640.1 3697.5 3704.0 3713.0 3734.9 139252.3 141280.0 150947.2 165026.0 166531.0 41879.6 42221.2 42255.2 42265.6 42337.8 44201.8 44306.3 44318.2 44373.6 44414.3 14447.6 14509.9 14525.2 14538.3 14551.0 11778.7 11812.3 11815.5 11832.4 11837.5 13381.7 13407.9 13434.8 13437.9 13531.1 5380.2 5453.5 5454.6 5455.5 5618.9 971.9 1006.6 1012.9 1026.7 1057.8 22672.7 23119.3 23175.9 23279.3 23293.3 1100.7 1125.9 1128.2 1134.8 1148.7 11731.5 12193.9 12240.8 12281.4 12281.5 200872.0 200903.0 201470.0 201509.0 202135.0 48380.3 48433.0 48446.0 48593.8 48636.3 1439.1 1461.2 1468.9 1472.2 1477.2 21632.6 21783.7 22539.5 22632.1 23012.5 34294.0 34496.3 34527.7 34607.8 34646.0 167297.0 167636.0 168765.0 169337.0 169577.0 31731.4 31907.0 33333.5 33422.5 33462.0 40773.2 41013.4 41797.6 42454.8 42723.8 30428.0 30550.7 30854.0 31372.0 31774.2 29930.9 29974.4 30045.0 31496.0 31742.0 33944.2 33957.3 34006.8 34024.8 34343.1 28548.9 28764.7 29348.3 29943.5 31623.2 21884.3 24159.0 24264.6 24437.5 24699.9 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | [#2] jquery.html Cycles:180 Average:32310.29 Median:20847.14 stddev:77721.11 (372.8%) stddev-sans-first:77902.23 06:24:44 INFO - PID 8869 | Values: 411.4 413.4 421.7 431.2 438.5 19789.2 20594.8 20598.7 22118.4 23108.0 22682.4 23290.0 23331.2 23347.9 23363.0 6037.8 6194.8 6196.9 6198.8 6211.8 6100.6 6132.0 6158.9 6159.9 6161.3 5616.0 5789.9 5834.6 5841.5 5843.4 5742.0 5803.7 5808.2 5836.9 5857.4 5238.3 5252.8 5254.2 5281.3 5286.4 25209.0 25700.4 26107.1 26196.6 26211.2 1885.0 1916.3 1921.7 1927.5 1931.8 16734.2 17140.8 17146.9 17244.7 17276.5 2193.8 2205.5 2237.7 2243.2 2290.3 118774.0 121986.4 122557.0 128847.0 129649.4 19407.0 20111.7 20243.0 20531.3 20800.5 39652.0 39763.6 39789.0 39874.4 40022.2 10746.1 10850.6 10959.7 10997.8 11003.0 8922.2 8946.1 8954.2 9009.9 9066.6 10712.9 10740.9 10964.9 10995.2 11083.4 4227.3 4268.6 4363.5 4367.9 4426.1 920.2 965.7 994.5 998.2 1009.8 19299.3 19831.8 20065.7 20645.7 20893.8 985.6 1002.4 1025.3 1038.8 1062.6 10628.6 11280.7 11322.8 11367.3 11369.9 454843.0 462180.0 465133.0 466232.0 472708.0 97105.5 100529.5 100593.5 101130.0 101496.0 1171.5 1204.9 1230.7 1231.5 1239.1 19819.1 20497.5 21119.3 21218.8 21350.6 47124.8 48524.3 48673.0 48881.8 49214.0 60561.7 60962.7 61016.4 61085.0 61323.0 9955.0 9991.2 10018.1 10109.9 10187.9 16185.3 16191.3 16336.0 16373.7 16433.0 14381.8 14388.3 14403.2 14446.7 14522.8 13022.4 13156.5 13387.2 13546.3 13745.5 13977.5 14080.4 14947.2 15018.5 15143.6 10034.1 10043.3 10066.5 10159.0 10162.9 14326.3 14333.9 14369.4 14381.3 14402.9 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | [#3] mootools.html Cycles:180 Average:6768.84 Median:3506.85 stddev:4303.72 (122.7%) stddev-sans-first:4296.21 06:24:44 INFO - PID 8869 | Values: 1305.4 1308.3 1363.7 1374.0 1384.3 10409.1 10720.2 10723.8 10775.8 10798.5 10474.3 10526.8 10534.7 10554.4 10585.4 2152.1 2231.4 2244.8 2282.3 2294.4 2243.0 2253.4 2255.8 2256.2 2257.7 2273.6 2287.7 2298.2 2303.8 2305.5 2274.2 2276.0 2286.1 2293.7 2322.5 2283.9 2313.2 2313.4 2313.5 2323.4 10481.9 10667.3 10686.5 10727.7 10742.6 6027.5 6204.8 6207.8 6220.4 6223.6 3448.5 3565.2 3636.5 3662.8 3686.8 1240.7 1244.1 1272.2 1288.2 1306.0 15721.4 15919.1 16001.4 16017.4 16595.8 5019.5 5044.0 5176.5 5180.7 5234.5 12143.7 12184.1 12184.8 12185.5 12194.3 5940.7 6099.2 6135.0 6267.1 6276.6 5659.5 5760.0 5774.0 5792.7 5806.1 4174.2 4179.8 4214.7 4224.4 4232.7 1230.9 1231.6 1233.2 1239.8 1243.1 797.4 826.8 838.8 850.9 870.0 6022.8 6071.2 6098.2 6116.3 6227.2 599.6 607.6 610.2 616.4 627.9 3989.9 4033.5 4044.7 4056.7 4078.8 13824.2 14270.6 14339.8 14477.5 14542.9 14130.6 14210.4 14273.6 14273.7 14333.6 7852.2 8350.8 8607.9 8611.5 8767.2 5618.7 6666.7 6693.5 7171.8 7185.1 11942.0 11948.5 12049.2 12091.6 12154.7 12249.9 12483.2 12546.3 12693.1 12746.9 9304.6 9438.2 9484.7 9498.5 9541.5 10408.7 10415.9 10498.5 10504.1 10538.9 8733.8 8898.5 8935.9 9067.5 9233.1 8767.2 8900.0 8900.9 8905.7 9245.0 7613.2 7679.6 7690.1 7738.5 7820.5 5846.5 5849.5 5880.6 5945.5 6111.1 5786.8 5947.5 5975.3 6172.6 6182.5 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | [#4] prototype.html Cycles:180 Average:5408.71 Median:5998.41 stddev:1566.86 (26.1%) stddev-sans-first:1525.51 06:24:44 INFO - PID 8869 | Values: 401.0 403.9 407.3 408.5 411.4 6192.8 6240.2 6254.2 6267.8 6272.1 5838.8 6043.2 6048.9 6071.7 6103.5 4720.0 4723.0 4745.2 4761.8 4761.9 4715.3 4718.3 4731.6 4742.1 4779.5 4661.7 4672.6 4673.0 4678.8 4679.2 4634.7 4660.3 4660.4 4664.4 4699.6 4095.4 4097.4 4098.8 4100.0 4106.2 5970.3 5980.9 5985.0 5986.6 5994.3 5349.2 5357.1 5365.5 5370.4 5379.2 6436.2 6438.8 6442.4 6445.6 6459.1 4442.2 4466.4 4475.1 4478.3 4495.3 6473.8 6662.5 6781.8 6837.6 6843.5 4738.5 4967.1 4974.8 4982.8 5003.8 5041.3 5065.6 5065.7 5080.5 5099.4 5618.4 5628.4 5636.7 5649.0 5667.8 6370.7 6536.7 6544.0 6549.6 6579.8 1738.4 1762.7 1764.5 1767.2 1773.9 1222.2 1248.2 1255.9 1272.8 1278.3 5737.0 5769.3 5803.2 5807.8 5822.0 6591.0 6632.7 6682.7 6686.5 6707.3 5690.9 5714.9 5719.0 5772.8 5790.0 3554.2 3563.5 3575.1 3579.3 3579.4 6439.6 6500.9 6512.4 6513.9 6515.8 6819.5 6824.5 6855.3 6878.4 6884.6 6476.5 6506.4 6530.5 6542.1 6554.3 6002.5 6642.7 6711.9 6723.6 6753.8 6682.5 6695.3 6703.7 6714.0 6717.4 6268.5 6378.1 6523.8 6528.9 6532.8 4581.2 4586.4 4594.0 4602.6 4616.6 6678.6 6717.2 6742.3 6747.1 6750.8 6429.6 6432.6 6512.3 6515.2 6549.8 6475.7 6494.4 6521.2 6527.7 6538.7 6393.3 6399.7 6417.7 6420.6 6442.4 6420.9 6586.5 6593.9 6599.6 6633.5 6271.0 6282.3 6300.4 6333.3 6397.7 06:24:44 INFO - PID 8869 | 06:24:44 INFO - PID 8869 | [#5] yui.html Cycles:180 Average:19579.15 Median:26066.67 stddev:24715.46 (94.8%) stddev-sans-first:24744.66 06:24:44 INFO - PID 8869 | Values: 821.0 850.4 853.5 864.9 877.7 13648.7 14318.0 16890.7 17492.3 17541.0 16663.6 17155.7 17175.1 17197.4 17290.7 920.0 936.3 940.6 962.5 1009.7 1022.9 1029.0 1032.2 1033.6 1039.8 993.2 1019.6 1022.2 1030.4 1033.6 1020.3 1025.3 1025.7 1025.7 1027.2 743.6 753.6 754.2 754.8 757.2 15734.1 16692.1 16791.0 16863.2 16933.7 18243.5 18548.9 18737.6 18764.1 18791.7 14231.1 14772.0 14884.3 14886.1 14899.8 3393.7 3469.8 3470.7 3482.0 3506.9 84750.0 86439.6 86548.6 86600.0 87470.0 14633.1 14820.1 15055.4 15145.2 15168.5 25899.0 26234.3 26329.3 26754.5 27056.0 8083.6 8253.1 8261.1 8275.3 8305.1 3261.2 3477.8 3860.6 3876.3 3900.3 4157.5 4240.1 4261.1 4261.7 4279.6 801.3 834.4 837.3 841.2 843.2 659.7 685.1 712.2 716.7 722.8 11380.9 12350.3 12671.4 12750.9 13077.9 651.0 659.8 675.1 680.1 707.5 5357.2 5628.2 5683.9 5703.5 5766.9 94660.0 94696.5 96075.5 97847.5 98268.0 69790.7 79337.6 79560.6 80693.6 83206.2 64794.4 65164.0 65324.7 65952.3 66083.3 10991.5 12093.2 12096.2 12198.6 12235.6 27625.3 28127.0 28674.0 29854.4 30081.7 53097.7 53837.5 54666.0 56327.0 56378.0 16638.3 16966.8 17100.6 17194.2 17275.7 18424.8 18894.7 19037.1 19039.7 19493.4 16896.2 16944.8 16945.6 17056.7 17388.4 17100.6 17101.0 17156.7 17234.0 17406.6 16748.5 16906.5 17455.9 17669.3 17849.2 10955.5 10985.5 11126.6 11429.9 11684.9 13244.2 13834.7 13858.9 13865.7 13890.2 06:24:44 INFO - PID 8869 | -------- Summary: end -------- 06:24:44 INFO - PID 8869 | 06:24:45 INFO - TEST-INFO | 8869: exit 0 06:24:45 INFO - TEST-OK | dromaeo_css | took 1144217ms 06:24:45 INFO - TEST-START | kraken 06:24:45 INFO - operating with platform_type : linux_ 06:24:45 INFO - Initialising browser for kraken test... 06:24:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42271/getInfo.html -profile /tmp/tmpHKVU1u/profile 06:24:45 INFO - TEST-INFO | started process 9299 (/builds/slave/test/build/application/firefox/firefox http://localhost:42271/getInfo.html) 06:25:20 INFO - TEST-INFO | 9299: killed by SIGTERM 06:25:20 INFO - Browser initialized. 06:25:20 INFO - Running cycle 1/1 for kraken test... 06:25:20 INFO - TEST-INFO | started process 9531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHKVU1u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:25:32 INFO - PID 9531 | RSS: Main: 192102400 06:25:32 INFO - PID 9531 | 06:25:34 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42271/tests/kraken/driver.html?testName=audio-beat-detection) 06:25:35 INFO - PID 9531 | RSS: Main: 196108288 06:25:35 INFO - PID 9531 | 06:25:38 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42271/tests/kraken/driver.html?testName=audio-dft) 06:25:39 INFO - PID 9531 | RSS: Main: 194994176 06:25:39 INFO - PID 9531 | 06:25:41 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42271/tests/kraken/driver.html?testName=audio-fft) 06:25:42 INFO - PID 9531 | RSS: Main: 189808640 06:25:42 INFO - PID 9531 | 06:25:44 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42271/tests/kraken/driver.html?testName=audio-oscillator) 06:25:45 INFO - PID 9531 | RSS: Main: 190636032 06:25:45 INFO - PID 9531 | 06:25:47 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:25:48 INFO - PID 9531 | RSS: Main: 190517248 06:25:48 INFO - PID 9531 | 06:25:51 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42271/tests/kraken/driver.html?testName=imaging-darkroom) 06:25:51 INFO - PID 9531 | RSS: Main: 191336448 06:25:51 INFO - PID 9531 | 06:25:55 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42271/tests/kraken/driver.html?testName=imaging-desaturate) 06:25:56 INFO - PID 9531 | RSS: Main: 190586880 06:25:56 INFO - PID 9531 | 06:25:58 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42271/tests/kraken/driver.html?testName=json-parse-financial) 06:25:59 INFO - PID 9531 | RSS: Main: 191967232 06:25:59 INFO - PID 9531 | 06:26:01 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:26:01 INFO - PID 9531 | RSS: Main: 192237568 06:26:01 INFO - PID 9531 | 06:26:03 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:26:04 INFO - PID 9531 | RSS: Main: 192253952 06:26:04 INFO - PID 9531 | 06:26:06 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:26:07 INFO - PID 9531 | RSS: Main: 192364544 06:26:07 INFO - PID 9531 | 06:26:09 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:26:10 INFO - PID 9531 | RSS: Main: 192237568 06:26:10 INFO - PID 9531 | 06:26:13 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:26:14 INFO - PID 9531 | RSS: Main: 191864832 06:26:14 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | Cycle 1(1): loaded http://localhost:42271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42271/tests/kraken/driver.html?testName=ai-astar) 06:26:16 INFO - PID 9531 | RSS: Main: 193224704 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | __start_tp_report 06:26:16 INFO - PID 9531 | _x_x_mozilla_page_load 06:26:16 INFO - PID 9531 | _x_x_mozilla_page_load_details 06:26:16 INFO - PID 9531 | |i|pagename|runs| 06:26:16 INFO - PID 9531 | |0;ai-astar;101;112;110;112;112;116;115;111;111;116 06:26:16 INFO - PID 9531 | |1;audio-beat-detection;147;121;122;176;121;127;121;122;143;128 06:26:16 INFO - PID 9531 | |2;audio-dft;165;165;161;163;164;166;168;168;169;165 06:26:16 INFO - PID 9531 | |3;audio-fft;73;74;73;115;74;73;74;75;98;74 06:26:16 INFO - PID 9531 | |4;audio-oscillator;83;82;108;84;83;86;97;84;84;113 06:26:16 INFO - PID 9531 | |5;imaging-gaussian-blur;115;115;114;114;114;114;114;113;113;114 06:26:16 INFO - PID 9531 | |6;imaging-darkroom;150;150;152;152;151;153;151;153;154;154 06:26:16 INFO - PID 9531 | |7;imaging-desaturate;95;94;95;94;95;95;95;96;95;94 06:26:16 INFO - PID 9531 | |8;json-parse-financial;56;56;55;57;57;56;57;57;57;60 06:26:16 INFO - PID 9531 | |9;json-stringify-tinderbox;55;56;55;54;55;55;55;55;55;55 06:26:16 INFO - PID 9531 | |10;stanford-crypto-aes;96;88;87;86;86;96;87;87;125;86 06:26:16 INFO - PID 9531 | |11;stanford-crypto-ccm;139;133;139;146;138;129;136;127;133;144 06:26:16 INFO - PID 9531 | |12;stanford-crypto-pbkdf2;183;199;187;204;199;205;196;185;209;203 06:26:16 INFO - PID 9531 | |13;stanford-crypto-sha256-iterative;73;72;75;73;72;78;73;72;74;76 06:26:16 INFO - PID 9531 | __end_tp_report 06:26:16 INFO - PID 9531 | __start_cc_report 06:26:16 INFO - PID 9531 | _x_x_mozilla_cycle_collect,1041 06:26:16 INFO - PID 9531 | __end_cc_report 06:26:16 INFO - PID 9531 | __startTimestamp1496409976443__endTimestamp 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | ------- Summary: start ------- 06:26:16 INFO - PID 9531 | Number of tests: 14 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:4.30 (3.8%) stddev-sans-first:2.28 06:26:16 INFO - PID 9531 | Values: 101.0 112.0 110.0 112.0 112.0 116.0 115.0 111.0 111.0 116.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:127.50 stddev:17.89 (14.0%) stddev-sans-first:18.22 06:26:16 INFO - PID 9531 | Values: 147.0 121.0 122.0 176.0 121.0 127.0 121.0 122.0 143.0 128.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#2] audio-dft Cycles:10 Average:165.40 Median:165.50 stddev:2.46 (1.5%) stddev-sans-first:2.60 06:26:16 INFO - PID 9531 | Values: 165.0 165.0 161.0 163.0 164.0 166.0 168.0 168.0 169.0 165.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#3] audio-fft Cycles:10 Average:80.30 Median:74.00 stddev:14.39 (19.4%) stddev-sans-first:15.02 06:26:16 INFO - PID 9531 | Values: 73.0 74.0 73.0 115.0 74.0 73.0 74.0 75.0 98.0 74.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.00 stddev:11.48 (13.7%) stddev-sans-first:11.86 06:26:16 INFO - PID 9531 | Values: 83.0 82.0 108.0 84.0 83.0 86.0 97.0 84.0 84.0 113.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 06:26:16 INFO - PID 9531 | Values: 115.0 115.0 114.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:152.50 stddev:1.49 (1.0%) stddev-sans-first:1.39 06:26:16 INFO - PID 9531 | Values: 150.0 150.0 152.0 152.0 151.0 153.0 151.0 153.0 154.0 154.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 06:26:16 INFO - PID 9531 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 94.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:1.32 (2.3%) stddev-sans-first:1.36 06:26:16 INFO - PID 9531 | Values: 56.0 56.0 55.0 57.0 57.0 56.0 57.0 57.0 57.0 60.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#9] json-stringify-tinderbox Cycles:10 Average:55.00 Median:55.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 06:26:16 INFO - PID 9531 | Values: 55.0 56.0 55.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:87.00 stddev:12.10 (13.9%) stddev-sans-first:12.77 06:26:16 INFO - PID 9531 | Values: 96.0 88.0 87.0 86.0 86.0 96.0 87.0 87.0 125.0 86.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:138.50 stddev:6.08 (4.4%) stddev-sans-first:6.37 06:26:16 INFO - PID 9531 | Values: 139.0 133.0 139.0 146.0 138.0 129.0 136.0 127.0 133.0 144.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.00 Median:201.00 stddev:9.08 (4.5%) stddev-sans-first:8.09 06:26:16 INFO - PID 9531 | Values: 183.0 199.0 187.0 204.0 199.0 205.0 196.0 185.0 209.0 203.0 06:26:16 INFO - PID 9531 | 06:26:16 INFO - PID 9531 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:73.50 stddev:1.99 (2.7%) stddev-sans-first:2.09 06:26:16 INFO - PID 9531 | Values: 73.0 72.0 75.0 73.0 72.0 78.0 73.0 72.0 74.0 76.0 06:26:16 INFO - PID 9531 | -------- Summary: end -------- 06:26:16 INFO - PID 9531 | 06:26:17 INFO - TEST-INFO | 9531: exit 0 06:26:17 INFO - TEST-OK | kraken | took 92594ms 06:26:17 INFO - SUITE-END | took 1236s 06:26:18 INFO - Completed test suite (00:20:37) 06:26:18 INFO - javascript benchmark 06:26:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7757.282996206689, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.241665791881, 3108.70467305606, 3111.113332700178, 3119.890803821865, 3125.9843700781507, 5835.387284335142, 5847.649141051535, 5852.356240813518, 5857.619254748438, 5884.941150588493, 5847.532197424206, 5867.383924687911, 5883.588148829573, 5900.970495147529, 5901.321348044966, 5416.0, 5508.586855985797, 5525.060739674265, 5555.30558680165, 5574.526165275956, 5405.459454054602, 5577.191307260445, 5585.273914391123, 5614.9438505615035, 5621.831345059655, 5580.330360356763, 5605.803796867111, 5607.243022192013, 5618.634788738719, 5653.0, 5429.728513574345, 5477.315335583052, 5485.259489968843, 5493.587980901449, 5527.336719593634, 5527.2538207341895, 5543.528800052, 5556.361018482846, 5564.693941833198, 5568.610197286192, 5902.32123305821, 5916.556258280603, 5931.258592675916, 5932.0, 5952.672603006833, 6303.243610766692, 6368.267649220353, 6441.194850643669, 6448.193975753031, 6454.903176452357, 6281.089242059876, 6354.936450635527, 6377.330380310047, 6386.680665966683, 6403.135576697133, 6060.181875446847, 6085.9087113692885, 6087.465756144067, 6088.634681919099, 6102.298235702907, 6683.665816709243, 6687.130673012477, 6689.565178263398, 6699.732010719516, 6699.832504187434, 6175.042868355412, 6264.060390941395, 6264.874702506016, 6271.090691849657, 6314.463270622048, 6197.938020619827, 6229.626222426669, 6296.307406185316, 6358.936410635834, 6484.25431075423, 6430.839229019219, 6440.194975628046, 6443.355664433613, 6445.967770161213, 6472.126262954441, 6437.0988061671405, 6447.806565803034, 6462.030695395728, 6466.321036291216, 6474.093626892239, 5234.0840352938085, 5276.973615131899, 5303.496167864047, 5307.893842123136, 5361.597880159004, 4534.478534968521, 4581.816727330936, 4596.7931443085145, 4629.074185162981, 4651.813927442932, 5608.784530110665, 5619.634723742944, 5621.15682647598, 5632.464915832989, 5667.6316039458125, 6332.905006424908, 6406.487481001509, 6433.0, 6439.903401448982, 6448.097266382711, 5625.46558076982, 5706.743196556165, 5707.828765137053, 5730.570207234474, 5736.741846616912, 5743.052396354555, 5743.483086522233, 5744.540436765049, 5758.452946970031, 5764.942350576524, 6152.538559607958, 6245.125682404376, 6326.778562750282, 6333.429991300805, 6356.0465930109, 6286.245650521967, 6382.93617063842, 6388.9041664374145, 6395.616263024047, 6396.520260980538, 6347.143135676624, 6354.046892966092, 6366.554341196072, 6367.204099487564, 6368.745250189941, 6360.363963603602, 6367.936320636734, 6389.808305750835, 6425.1968503937005, 6428.4214420702365, 6341.0805433212845, 6344.96827515878, 6436.002419624966, 6437.163168788027, 6464.127342808661, 6325.652089135142, 6332.0818481320875, 6338.873222535616, 6341.619502829845, 6343.080253363329, 6132.845047373448, 6253.249610046823, 6289.308176100534, 6325.272593651789, 6357.14178585885, 6105.389461053859, 6108.0, 6178.567500274938, 6179.165812615419, 6183.288921773874, 6373.330800265905, 6405.135306733531, 6406.0390941359165, 6414.294427613052, 6422.903656445251, 6166.136740856194, 6198.163247961467, 6204.472619827366, 6206.875862482815, 6219.813405597839], "value": 5893.779977062847, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.1290013283661, 744.1814004594945, 768.3123604374085, 1132.8130858408363, 1156.2773266708307, 36070.458943115875, 37139.25721485572, 37163.0, 37223.44164837523, 37373.252534949315, 39425.40861887067, 39524.802375988154, 39559.802200988954, 39608.40587391184, 39668.40497392541, 7135.179454362763, 7230.710771569131, 7309.744158954438, 7403.8149046274, 7421.703131874719, 7243.0, 7303.744368947088, 7339.045924029869, 7358.2641735826255, 7361.889571656429, 7251.891221631679, 7296.744613938513, 7301.525400848955, 7345.265473452639, 7363.926360736405, 7269.236730143338, 7308.744193953239, 7328.303811137934, 7332.450066245053, 7348.963255183743, 7202.459815513805, 7262.564246145248, 7264.34620884123, 7299.160596531414, 7302.415806735448, 34797.0, 34997.30005399903, 35179.1205219869, 35208.64791352105, 35238.0, 3462.8441720122655, 3488.215151590897, 3499.0202743231935, 3505.193805424741, 3510.543629328171, 28279.434411311864, 28497.0, 28578.285542861595, 28591.0, 28616.42767144666, 3640.10817349751, 3697.5008373869723, 3704.0, 3712.962870371289, 3734.869279575229, 139252.30373848067, 141280.0, 150947.24526377406, 165026.0, 166530.99999999878, 41879.58120418818, 42221.15557688829, 42255.15489690189, 42265.57734422616, 42337.78831105825, 44201.77899110484, 44306.33540496895, 44318.23295019895, 44373.55626443759, 44414.33378499325, 14447.566573002827, 14509.85490145106, 14525.201113938834, 14538.345774440175, 14551.0, 11778.705532361759, 11812.291262524275, 11815.527378904919, 11832.408379581158, 11837.46731397072, 13381.732365352833, 13407.865921340857, 13434.798478022838, 13437.865621343857, 13531.120477168952, 5380.192971054373, 5453.536449401766, 5454.563634909197, 5455.536279416294, 5618.859528511738, 971.8736564246602, 1006.5571148694551, 1012.8531362952479, 1026.7381817636456, 1057.7672911959357, 22672.659910101363, 23119.306420807403, 23175.88412057929, 23279.3016209514, 23293.301200963997, 1100.6753007862699, 1125.876153623101, 1128.1933417606451, 1134.846795682589, 1148.735790768128, 11731.530738770523, 12193.939030304791, 12240.837120473541, 12281.392116916324, 12281.508739650493, 200872.0, 200903.0, 201470.0, 201509.0, 202135.0, 48380.27429588559, 48433.0, 48446.0, 48593.75703121462, 48636.27045594319, 1439.1221354973459, 1461.1890400827642, 1468.8678018978344, 1472.1535117307592, 1477.2170749502995, 21632.567348652625, 21783.673244901023, 22539.5492090154, 22632.094716211166, 23012.53974920459, 34294.0, 34496.310073798886, 34527.65472345244, 34607.82696086504, 34646.0, 167297.0, 167636.0, 168765.0, 169337.0, 169577.0, 31731.36537269288, 31907.0, 33333.49999749957, 33422.498662519596, 33462.0, 40773.1845363097, 41013.38479922744, 41797.58202417937, 42454.78772606117, 42723.78638106914, 30428.0, 30550.694493054783, 30854.0, 31372.0, 31774.205644859063, 29930.85034574813, 29974.400511990076, 30045.0, 31496.0, 31742.0, 33944.15139621529, 33957.32085358328, 34006.82996585001, 34024.82987585046, 34343.141421464665, 28548.85725571359, 28764.71235287704, 29348.26629334198, 29943.471763370824, 31623.20941976469, 21884.343469695934, 24159.0, 24264.636030459205, 24437.51124977526, 24699.87650061738], "value": 16031.245573538283, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.4281149202609, 413.4088253797069, 421.6668831623018, 431.16354272710936, 438.53296239504925, 19789.208431662733, 20594.794052059493, 20598.691019634698, 22118.447038823997, 23108.0, 22682.432939176488, 23290.0, 23331.183408580702, 23347.883260583716, 23363.0, 6037.849053773658, 6194.752209911598, 6196.876062478747, 6198.8450288742915, 6211.782587609435, 6100.633961962274, 6132.0, 6158.938410615881, 6159.9384006160035, 6161.260648722159, 5616.0, 5789.913151302734, 5834.649921004753, 5841.5326773858205, 5843.386444423338, 5742.0, 5803.6517808931385, 5808.2449281593545, 5836.9124463133085, 5857.355690874, 5238.292830467876, 5252.763625636855, 5254.238135470336, 5281.339832520935, 5286.365636123652, 25209.0, 25700.357491062685, 26107.08625198128, 26196.60705089425, 26211.213663590122, 1884.9623007539772, 1916.290972340229, 1921.702136168896, 1927.5181204698824, 1931.8357939575153, 16734.246958886757, 17140.828591714173, 17146.914265428717, 17244.655106897793, 17276.481705548853, 2193.824494040473, 2205.5368372641765, 2237.7202849643795, 2243.2148747938254, 2290.347251033464, 118774.0, 121986.39006804998, 122557.0, 128847.0, 129649.35175324157, 19407.0, 20111.698324525143, 20243.0, 20531.28140515075, 20800.47998800042, 39652.0, 39763.6200922919, 39789.0, 39874.40188397176, 40022.199556008716, 10746.086582640562, 10850.565977360817, 10959.671209863716, 10997.835032474359, 11003.029182120943, 8922.152395522415, 8946.060663630225, 8954.23888969432, 9009.909900901039, 9066.592003359732, 10712.892871071346, 10740.946295268473, 10964.945175274232, 10995.175361847894, 11083.445827708743, 4227.260229459832, 4268.637165840876, 4363.47638283408, 4367.890802729958, 4426.1369033038245, 920.1810388754014, 965.7199412170532, 994.5275993902839, 998.1665309466676, 1009.7677534167183, 19299.324523641884, 19831.801681983285, 20065.699014514794, 20645.690314645293, 20893.791062089487, 985.5909797433984, 1002.3885429887763, 1025.3130402630256, 1038.787048655024, 1062.5749700120014, 10628.627998020187, 11280.71798205035, 11322.830157547643, 11367.261128026652, 11369.943150284196, 454843.0, 462180.0, 465133.0, 466232.0, 472708.0, 97105.51447242718, 100529.49735251423, 100593.49703251584, 101130.0, 101496.0, 1171.5489536528328, 1204.8855358740905, 1230.6615680687703, 1231.4581584102966, 1239.114033466076, 19819.108140134016, 20497.48756281105, 21119.260825871635, 21218.78781212168, 21350.572988539832, 47124.764376177896, 48524.2721359187, 48673.0, 48881.755591221816, 49214.0, 60561.69719151376, 60962.695186525554, 61016.38983610107, 61085.0, 61323.0, 9955.0, 9991.200703943812, 10018.098371146632, 10109.949450252701, 10187.898121018992, 16185.30054344324, 16191.271392787588, 16336.0, 16373.672526549164, 16433.0, 14381.7842732357, 14388.273407191204, 14403.207823569805, 14446.711065778836, 14522.782158267846, 13022.413991370222, 13156.473741050251, 13387.196768193939, 13546.32268386557, 13745.518906838613, 13977.510787122403, 14080.366383512972, 14947.177905215318, 15018.549443516711, 15143.621409464851, 10034.066890463757, 10043.296969212084, 10066.54700538463, 10159.0, 10162.898371016196, 14326.283685815459, 14333.928330358282, 14369.425222991382, 14381.339452201377, 14402.855971440571], "value": 11229.030252326223, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.3864683598706, 1308.2608326295644, 1363.6795353092025, 1373.9862601373984, 1384.3147641917246, 10409.06318431341, 10720.195985301114, 10723.78552428951, 10775.78448431032, 10798.51406686699, 10474.26680132389, 10526.7893742314, 10534.683959481208, 10554.419506927115, 10585.364878107299, 2152.1068756466125, 2231.4309850987975, 2244.820414366851, 2282.2696737044153, 2294.4149241943283, 2243.0354947372625, 2253.43664083979, 2255.793150664395, 2256.17649557911, 2257.7064981552458, 2273.59075366434, 2287.714035745532, 2298.2071185440996, 2303.7581053989343, 2305.5158416732424, 2274.2267629006133, 2276.0440614941763, 2286.1084177170915, 2293.71328583927, 2322.523882604079, 2283.9265545193734, 2313.1903833658084, 2313.421644588853, 2313.456337760625, 2323.384303159647, 10481.947590262076, 10667.293233082748, 10686.51910664022, 10727.678169654922, 10742.624008159755, 6027.457528822427, 6204.81385558434, 6207.782727604557, 6220.409061139185, 6223.564350495467, 3448.499967504698, 3565.1978304881454, 3636.454506834611, 3662.8168591570316, 3686.8340924658455, 1240.6712221261373, 1244.1104610203747, 1272.1921579796804, 1288.2206265209506, 1305.9542915997895, 15721.37114515418, 15919.124448155348, 16001.359945602162, 16017.439389621424, 16595.751063734053, 5019.548240658358, 5044.041632089891, 5176.456472070454, 5180.740962951837, 5234.450382709761, 12143.696407589881, 12184.147109702235, 12184.756304873852, 12185.51257949672, 12194.329311887754, 5940.70296485174, 6099.176611157525, 6134.969325153345, 6267.122602835607, 6276.556392029859, 5659.490645841812, 5760.0, 5773.971130144407, 5792.681402522901, 5806.071028635398, 4174.186033723395, 4179.811908464127, 4214.726042807208, 4224.38746381779, 4232.724872883253, 1230.8584512781144, 1231.612042206702, 1233.2353940556732, 1239.7892358299111, 1243.0925424440209, 797.3740613618397, 826.7519744076753, 838.8196537744415, 850.8511010573125, 870.0299166429386, 6022.849428764228, 6071.210742603522, 6098.207233059674, 6116.29662588808, 6227.19046523949, 599.6462087368474, 607.5807692692028, 610.215872603711, 616.3990109643152, 627.9309275979639, 3989.900252493711, 4033.5361433435532, 4044.6764258859807, 4056.716029877939, 4078.8164532596106, 13824.170549767046, 14270.571882843531, 14339.78490322646, 14477.493287734878, 14542.927285363718, 14130.646733831736, 14210.431582736575, 14273.643158920902, 14273.714525709427, 14333.56999290002, 7852.214778522102, 8350.832983340299, 8607.870881936777, 8611.526366049928, 8767.21095101444, 5618.67961029166, 6666.666666666744, 6693.497987650849, 7171.816650252858, 7185.137783466018, 11942.0, 11948.462319195483, 12049.216800907912, 12091.57679481214, 12154.696132596755, 12249.877501224873, 12483.188592741442, 12546.30254495288, 12693.111482196158, 12746.936265318614, 9304.627814887328, 9438.197753191056, 9484.668036618685, 9498.477583732962, 9541.522923853918, 10408.687739367831, 10415.895841041493, 10498.47507624631, 10504.10715089226, 10538.89461105379, 8733.825093765692, 8898.510581917797, 8935.865962010444, 9067.455952642862, 9233.122853328923, 8767.24655068976, 8900.020997690379, 8900.86648700283, 8905.688300909436, 9244.95377523108, 7613.200613935457, 7679.616019199129, 7690.115636701851, 7738.535687858746, 7820.530768153929, 5846.473817356288, 5849.502792262705, 5880.558958078247, 5945.464908158287, 6111.1444397785235, 5786.768529258951, 5947.524198064231, 5975.34271230173, 6172.567920245539, 6182.536309776695], "value": 5074.119575863766, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9959900400996, 403.92931237033514, 407.3218091877025, 408.51998901290995, 411.3562275039563, 6192.752289908398, 6240.162300185388, 6254.186955695754, 6267.811965641033, 6272.090546870707, 5838.766449342022, 6043.214382130328, 6048.879022419549, 6071.6964151792345, 6103.511719062475, 4720.0175926111115, 4722.97638511807, 4745.240761478164, 4761.8095276188915, 4761.857144285677, 4715.2927060940965, 4718.292256161586, 4731.57415832575, 4742.099001189763, 4779.52204779521, 4661.74360410179, 4672.6261899047995, 4672.953270467286, 4678.7894544745395, 4679.227927391977, 4634.698744581591, 4660.252405347995, 4660.440747110351, 4664.3936288282475, 4699.600533954618, 4095.385692146172, 4097.364908439196, 4098.815553300079, 4099.97950010251, 4106.2198182345255, 5970.313413957364, 5980.8504787380225, 5985.0, 5986.640801551877, 5994.294478834138, 5349.224362467459, 5357.142857142839, 5365.463453654604, 5370.409254981949, 5379.193121031837, 6436.207413327996, 6438.839029024265, 6442.355764423567, 6445.613263204176, 6459.06343580183, 4442.155990361821, 4466.352378905073, 4475.149721552894, 4478.328250762379, 4495.280755079204, 6473.838154046187, 6662.533622646368, 6781.779279729647, 6837.589744615339, 6843.520953533254, 4738.549837765476, 4967.105920934267, 4974.77613507393, 4982.825601103944, 5003.774830132653, 5041.344625198701, 5065.594752419797, 5065.721385323769, 5080.542751152414, 5099.388073431138, 5618.43815618443, 5628.380878103405, 5636.746346414421, 5648.983183027012, 5667.801626943037, 6370.674703733104, 6536.673166341664, 6544.018397240452, 6549.6070235786, 6579.80260592183, 1738.4089409600545, 1762.6739053275185, 1764.5323989142887, 1767.1959258537593, 1773.9024353660673, 1222.1811386371153, 1248.2073883084306, 1255.8618551959278, 1272.8281681973, 1278.2713853103828, 5737.0, 5769.307683078057, 5803.245578074823, 5807.767689292466, 5822.0, 6591.0, 6632.701528431136, 6682.665866706645, 6686.465082793365, 6707.27137368164, 5690.857728556819, 5714.885702285931, 5718.999175144333, 5772.797952071742, 5789.9710501447225, 3554.182538016271, 3563.483294922222, 3575.0515968797067, 3579.319929213441, 3579.4451859961746, 6439.61362318253, 6500.934990650033, 6512.446442052478, 6513.869722605521, 6515.837104072436, 6819.488538359643, 6824.53272546395, 6855.280195579492, 6878.415334696607, 6884.621345825927, 6476.514261430318, 6506.41442270198, 6530.510211734139, 6542.149520562297, 6554.279029306677, 6002.532754876576, 6642.701078451578, 6711.865762684622, 6723.630200339028, 6753.763618273337, 6682.498812589172, 6695.330466953461, 6703.698333574904, 6714.0, 6717.361850624183, 6268.4671802897265, 6378.1070650108095, 6523.836904077436, 6528.869422611426, 6532.804015879531, 4581.198290299251, 4586.449626044896, 4593.954060459487, 4602.585767280961, 4616.6306695464955, 6678.565893216925, 6717.16035495563, 6742.258351581224, 6747.122874026345, 6750.831229219309, 6429.6142231466265, 6432.64914367988, 6512.251091124392, 6515.218173819172, 6549.770758023446, 6475.708593113227, 6494.447971922439, 6521.184851893513, 6527.706253218712, 6538.673066346759, 6393.29673735898, 6399.680015999088, 6417.711202995783, 6420.6147631142285, 6442.420182183626, 6420.903686444801, 6586.473082153512, 6593.934060659332, 6599.571027883172, 6633.502487313567, 6271.0, 6282.340354262737, 6300.432961033529, 6333.303336633059, 6397.744090236339], "value": 4930.4034514501745, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.9753707388772, 850.3707256630092, 853.4879072556467, 864.8745931839883, 877.679646928871, 13648.658783530416, 14318.0, 16890.746638800414, 17492.30030798768, 17541.035243061626, 16663.583410414714, 17155.656886862256, 17175.05537195454, 17197.398091066814, 17290.740638890427, 919.9908000919972, 936.283742936654, 940.6378544260441, 962.4754508792724, 1009.6617633092923, 1022.9386236825777, 1028.953697083633, 1032.1536340201037, 1033.6020632056675, 1039.7920415916847, 993.2203220471931, 1019.6329321444274, 1022.2077889635517, 1030.4435604773414, 1033.5555711044249, 1020.3214862116683, 1025.3437799808128, 1025.661531694539, 1025.7281820317585, 1027.1525991057333, 743.5724458436421, 753.5855279596256, 754.2231501553409, 754.7660225330166, 757.2087168908504, 15734.055956642638, 16692.081935493545, 16791.0, 16863.24115414797, 16933.661326773396, 18243.45269641913, 18548.90725546377, 18737.62524749511, 18764.0617969101, 18791.718124228148, 14231.07498012626, 14772.0, 14884.255787210595, 14886.10683358991, 14899.77650335246, 3393.6606339366112, 3469.826508674556, 3470.7396945228957, 3481.9651803481893, 3506.947395789065, 84750.0, 86439.56780216121, 86548.5672571633, 86600.0, 87470.0, 14633.122012679274, 14820.110793352433, 15055.397784088515, 15145.167015814015, 15168.469103581321, 25899.0, 26234.344141396617, 26329.341766455993, 26754.464910701678, 27056.0, 8083.585372559778, 8253.050899146554, 8261.1325810789, 8275.296599788964, 8305.086440491541, 3261.2336101016244, 3477.8434970425888, 3860.613938606173, 3876.3022655922205, 3900.31744444721, 4157.542670306264, 4240.1307731914885, 4261.083866968616, 4261.74429534229, 4279.572042795696, 801.2908575910245, 834.4367551902442, 837.3385025829648, 841.2008591837656, 843.2200214801406, 659.6536818170403, 685.1093578348227, 712.1596516110935, 716.7097325583147, 722.8048426924701, 11380.94309528447, 12350.320732359627, 12671.429785659668, 12750.872491275153, 13077.869221307856, 650.9707063182168, 659.7855696898528, 675.1021141881287, 680.1226417920925, 707.4729326651677, 5357.196420536873, 5628.183913332544, 5683.857903552362, 5703.486686198262, 5766.884662306814, 94660.0, 94696.52651736834, 96075.51962240144, 97847.51076244573, 98268.01731982731, 69790.65104674445, 79337.60331198307, 79560.60219698865, 80693.59653201814, 83206.16793831984, 64794.352056478834, 65164.0, 65324.673376633764, 65952.34047659462, 66083.33916660772, 10991.505382257978, 12093.153479256367, 12096.153269271066, 12198.634040978784, 12235.632931012084, 27625.309367265978, 28127.015554455495, 28674.0, 29854.402911942074, 30081.69918300789, 53097.734511327195, 53837.46162538324, 54666.0, 56327.0, 56378.0, 16638.334466621684, 16966.83033169702, 17100.572485687957, 17194.22625981809, 17275.654486910444, 18424.81575184231, 18894.716579251046, 19037.14332854997, 19039.714404284223, 19493.415197544095, 16896.23966921516, 16944.830551694326, 16945.576360591083, 17056.744148838025, 17388.391406300718, 17100.572485687957, 17101.0, 17156.656866862344, 17234.0, 17406.564835879206, 16748.497545073667, 16906.492805215865, 17455.912720436318, 17669.29322827124, 17849.196786144916, 10955.452227388758, 10985.505652245827, 11126.61056863006, 11429.942850285695, 11684.88315116838, 13244.20534767917, 13834.723305533631, 13858.930705346813, 13865.72268554603, 13890.166590004632], "value": 8208.827017972282, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 110.0, 112.0, 112.0, 116.0, 115.0, 111.0, 111.0, 116.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 122.0, 176.0, 121.0, 127.0, 121.0, 122.0, 143.0, 128.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 161.0, 163.0, 164.0, 166.0, 168.0, 168.0, 169.0, 165.0], "value": 165.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 115.0, 74.0, 73.0, 74.0, 75.0, 98.0, 74.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 108.0, 84.0, 83.0, 86.0, 97.0, 84.0, 84.0, 113.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 152.0, 152.0, 151.0, 153.0, 151.0, 153.0, 154.0, 154.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 57.0, 57.0, 56.0, 57.0, 57.0, 57.0, 60.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 88.0, 87.0, 86.0, 86.0, 96.0, 87.0, 87.0, 125.0, 86.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 133.0, 139.0, 146.0, 138.0, 129.0, 136.0, 127.0, 133.0, 144.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 199.0, 187.0, 204.0, 199.0, 205.0, 196.0, 185.0, 209.0, 203.0], "value": 197.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 75.0, 73.0, 72.0, 78.0, 73.0, 72.0, 74.0, 76.0], "value": 73.8, "unit": "score"}]}]} 06:26:18 INFO - Return code: 0 06:26:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:26:18 INFO - # TBPL SUCCESS # 06:26:18 INFO - Running post-action listener: _resource_record_post_action 06:26:18 INFO - [mozharness: 2017-06-02 13:26:18.351931Z] Finished run-tests step (success) 06:26:18 INFO - Running post-run listener: _resource_record_post_run 06:26:18 INFO - Total resource usage - Wall time: 1255s; CPU: 13.0%; Read bytes: 196608; Write bytes: 443293696; Read time: 64; Write time: 528624 06:26:18 INFO - TinderboxPrint: CPU usage
12.7% 06:26:18 INFO - TinderboxPrint: I/O read bytes / time
196,608 / 64 06:26:18 INFO - TinderboxPrint: I/O write bytes / time
443,293,696 / 528,624 06:26:18 INFO - TinderboxPrint: CPU idle
8,741.8 (87.3%) 06:26:18 INFO - TinderboxPrint: CPU user
1,216.9 (12.1%) 06:26:18 INFO - TinderboxPrint: Swap in / out
0 / 0 06:26:18 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 116019200; Read time: 0; Write time: 217376 06:26:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:26:18 INFO - run-tests - Wall time: 1238s; CPU: 13.0%; Read bytes: 196608; Write bytes: 327274496; Read time: 64; Write time: 311248 06:26:19 INFO - Running post-run listener: _upload_blobber_files 06:26:19 INFO - Blob upload gear active. 06:26:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:26:19 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 06:26:19 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:26:19 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:26:19 INFO - (blobuploader) - INFO - Open directory for files ... 06:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:20 INFO - (blobuploader) - INFO - Done attempting. 06:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:26:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:26 INFO - (blobuploader) - INFO - Done attempting. 06:26:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:26:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:26:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:27 INFO - (blobuploader) - INFO - Done attempting. 06:26:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:26:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:26:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:28 INFO - (blobuploader) - INFO - Done attempting. 06:26:28 INFO - (blobuploader) - INFO - Iteration through files over. 06:26:28 INFO - Return code: 0 06:26:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:26:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:26:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56466604718ee89eaa5f288b56a486214f1e709400ba66bff74bd5af2f85c193ba3b6fc36ef51d4455f8396eb237210489c1260216aa7ae4476f3231c7b581f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c51f11156b74b65781f77025cf824adf36d65f2264194701fc694674e1ef611f0c37e55ac302c4448c6168aa46447ec50a84ee9aa79c4a1433c7ce5a6f2ca548", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bccb3852ae6ee92a62aa4cf039d68a12a50742c210a0a7e63a23857e167d72d16a7c1bacd0b23fe53064a8bbf0f12afbab9bf178f95654bb17717e7bd88fba9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49f4f897877694a50835deaf0da9c233d95ae2333a9bd015d68e773b2439aaec8312a74175fb73fd1249c023aaa4a521c52fa885b51164d128b80e382e266617"} 06:26:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:26:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:26:28 INFO - Contents: 06:26:28 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56466604718ee89eaa5f288b56a486214f1e709400ba66bff74bd5af2f85c193ba3b6fc36ef51d4455f8396eb237210489c1260216aa7ae4476f3231c7b581f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c51f11156b74b65781f77025cf824adf36d65f2264194701fc694674e1ef611f0c37e55ac302c4448c6168aa46447ec50a84ee9aa79c4a1433c7ce5a6f2ca548", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bccb3852ae6ee92a62aa4cf039d68a12a50742c210a0a7e63a23857e167d72d16a7c1bacd0b23fe53064a8bbf0f12afbab9bf178f95654bb17717e7bd88fba9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49f4f897877694a50835deaf0da9c233d95ae2333a9bd015d68e773b2439aaec8312a74175fb73fd1249c023aaa4a521c52fa885b51164d128b80e382e266617"} 06:26:28 INFO - Running post-run listener: copy_logs_to_upload_dir 06:26:28 INFO - Copying logs to upload dir... 06:26:28 INFO - mkdir: /builds/slave/test/build/upload/logs 06:26:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1320.552134 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 0 secs) (at 2017-06-02 06:26:28.701299) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 06:26:28.705721) ========= 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=745a4373a2a16df560f216e000000264-1496406752.244137-24443858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56466604718ee89eaa5f288b56a486214f1e709400ba66bff74bd5af2f85c193ba3b6fc36ef51d4455f8396eb237210489c1260216aa7ae4476f3231c7b581f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c51f11156b74b65781f77025cf824adf36d65f2264194701fc694674e1ef611f0c37e55ac302c4448c6168aa46447ec50a84ee9aa79c4a1433c7ce5a6f2ca548", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bccb3852ae6ee92a62aa4cf039d68a12a50742c210a0a7e63a23857e167d72d16a7c1bacd0b23fe53064a8bbf0f12afbab9bf178f95654bb17717e7bd88fba9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49f4f897877694a50835deaf0da9c233d95ae2333a9bd015d68e773b2439aaec8312a74175fb73fd1249c023aaa4a521c52fa885b51164d128b80e382e266617"} build_url:https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011089 build_url: 'https://queue.taskcluster.net/v1/task/SkoD75AaTneoUY332DUFRA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56466604718ee89eaa5f288b56a486214f1e709400ba66bff74bd5af2f85c193ba3b6fc36ef51d4455f8396eb237210489c1260216aa7ae4476f3231c7b581f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c51f11156b74b65781f77025cf824adf36d65f2264194701fc694674e1ef611f0c37e55ac302c4448c6168aa46447ec50a84ee9aa79c4a1433c7ce5a6f2ca548", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bccb3852ae6ee92a62aa4cf039d68a12a50742c210a0a7e63a23857e167d72d16a7c1bacd0b23fe53064a8bbf0f12afbab9bf178f95654bb17717e7bd88fba9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49f4f897877694a50835deaf0da9c233d95ae2333a9bd015d68e773b2439aaec8312a74175fb73fd1249c023aaa4a521c52fa885b51164d128b80e382e266617"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 06:26:28.731218) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:26:28.731550) ========= 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=745a4373a2a16df560f216e000000264-1496406752.244137-24443858 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 06:26:28.814162) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 06:26:28.814673) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 06:26:28.815114) ========= ========= Total master_lag: 0.19 =========