builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-029 starttime: 1502878306.91 results: success (0) revision: c498777e8f39a3027e44d7badad05bd3a3f86f08 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:46.910147) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:46.910582) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:46.910874) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:46.924656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:46.924953) ========= 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=ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-16 03:11:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-08-16 03:11:47 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098188 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.037849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.038150) ========= 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=ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019221 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.090156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.090469) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c498777e8f39a3027e44d7badad05bd3a3f86f08 --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 c498777e8f39a3027e44d7badad05bd3a3f86f08 --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=ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-16 03:11:47,330 truncating revision to first 12 chars 2017-08-16 03:11:47,330 Setting DEBUG logging. 2017-08-16 03:11:47,330 attempt 1/10 2017-08-16 03:11:47,330 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c498777e8f39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-16 03:11:47,694 unpacking tar archive at: mozilla-central-c498777e8f39/testing/mozharness/ program finished with exit code 0 elapsedTime=0.714206 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.822372) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.825715) ========= script_repo_revision: c498777e8f39a3027e44d7badad05bd3a3f86f08 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.826093) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.826352) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 03:11:47.838159) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-16 03:11:47.838421) ========= /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=ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:11:48 INFO - MultiFileLogger online at 20170816 03:11:48 in /builds/slave/test 03:11:48 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 03:11:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:11:48 INFO - {'append_to_log': False, 03:11:48 INFO - 'base_work_dir': '/builds/slave/test', 03:11:48 INFO - 'blob_upload_branch': 'Firefox', 03:11:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:11:48 INFO - 'branch': 'Firefox', 03:11:48 INFO - 'buildbot_json_path': 'buildprops.json', 03:11:48 INFO - 'code_coverage': False, 03:11:48 INFO - 'config_files': ('talos/linux_config.py',), 03:11:48 INFO - 'default_actions': ('clobber', 03:11:48 INFO - 'read-buildbot-config', 03:11:48 INFO - 'download-and-extract', 03:11:48 INFO - 'populate-webroot', 03:11:48 INFO - 'create-virtualenv', 03:11:48 INFO - 'install', 03:11:48 INFO - 'setup-mitmproxy', 03:11:48 INFO - 'run-tests'), 03:11:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:11:48 INFO - 'disable_ccov_upload': False, 03:11:48 INFO - 'download_minidump_stackwalk': True, 03:11:48 INFO - 'download_symbols': 'ondemand', 03:11:48 INFO - 'enable_stylo': False, 03:11:48 INFO - 'enable_webrender': False, 03:11:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:11:48 INFO - 'tooltool.py': '/tools/tooltool.py', 03:11:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:11:48 INFO - '/tools/misc-python/virtualenv.py')}, 03:11:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:11:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:11:48 INFO - 'gecko_profile': False, 03:11:48 INFO - 'gecko_profile_interval': 0, 03:11:48 INFO - 'installer_path': 'installer.exe', 03:11:48 INFO - 'log_level': 'info', 03:11:48 INFO - 'log_name': 'talos', 03:11:48 INFO - 'log_to_console': True, 03:11:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:11:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:11:48 INFO - 'opt_config_files': (), 03:11:48 INFO - 'pip_index': False, 03:11:48 INFO - 'suite': 'dromaeojs-e10s', 03:11:48 INFO - 'system_bits': '32', 03:11:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:11:48 INFO - 'title': 'talos-linux64-ix-029', 03:11:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:11:48 INFO - 'use_talos_json': True, 03:11:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:11:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:11:48 INFO - 'work_dir': 'build'} 03:11:48 INFO - [mozharness: 2017-08-16 10:11:48.175214Z] Running clobber step. 03:11:48 INFO - Running pre-action listener: _resource_record_pre_action 03:11:48 INFO - Running main action method: clobber 03:11:48 INFO - rmtree: /builds/slave/test/build 03:11:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:11:49 INFO - Running post-action listener: _resource_record_post_action 03:11:49 INFO - [mozharness: 2017-08-16 10:11:49.207995Z] Finished clobber step (success) 03:11:49 INFO - [mozharness: 2017-08-16 10:11:49.208098Z] Running read-buildbot-config step. 03:11:49 INFO - Running pre-action listener: _resource_record_pre_action 03:11:49 INFO - Running main action method: read_buildbot_config 03:11:49 INFO - Using buildbot properties: 03:11:49 INFO - { 03:11:49 INFO - "project": "", 03:11:49 INFO - "product": "firefox", 03:11:49 INFO - "who": "cbook@mozilla.com", 03:11:49 INFO - "installer_path": "public/build/target.tar.bz2", 03:11:49 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 03:11:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:11:49 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 03:11:49 INFO - "stage_platform": "linux64-stylo", 03:11:49 INFO - "basedir": "/builds/slave/test", 03:11:49 INFO - "buildnumber": 87, 03:11:49 INFO - "platform": "ubuntu64_hw_stylo", 03:11:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:11:49 INFO - "slavebuilddir": "test", 03:11:49 INFO - "taskId": "dGfwlDEpTMS8I9hPeKJeXA", 03:11:49 INFO - "branch": "mozilla-central", 03:11:49 INFO - "script_repo_revision": "production", 03:11:49 INFO - "revision": "c498777e8f39a3027e44d7badad05bd3a3f86f08", 03:11:49 INFO - "slavename": "talos-linux64-ix-029", 03:11:49 INFO - "repo_path": "mozilla-central" 03:11:49 INFO - } 03:11:49 INFO - Finding installer, test and symbols from parent task. 03:11:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGfwlDEpTMS8I9hPeKJeXA'}, attempt #1 03:11:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGfwlDEpTMS8I9hPeKJeXA'}, attempt #1 03:11:50 INFO - Task dependencies: HuMrrxyuS1iW8gGOnr-3Zg 03:11:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg'}, attempt #1 03:11:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2 03:11:50 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.test_packages.json 03:11:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.crashreporter-symbols.zip 03:11:50 INFO - Running post-action listener: _resource_record_post_action 03:11:50 INFO - [mozharness: 2017-08-16 10:11:50.595168Z] Finished read-buildbot-config step (success) 03:11:50 INFO - [mozharness: 2017-08-16 10:11:50.595390Z] Running download-and-extract step. 03:11:50 INFO - Running pre-action listener: _resource_record_pre_action 03:11:50 INFO - Running main action method: download_and_extract 03:11:50 INFO - mkdir: /builds/slave/test/build/tests 03:11:50 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')]} 03:11:50 INFO - trying https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.test_packages.json 03:11:50 INFO - Downloading https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:11:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:11:51 INFO - Downloaded 989 bytes. 03:11:51 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:11:51 INFO - Using the following test package requirements: 03:11:51 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:11:51 INFO - u'common': [u'target.common.tests.zip'], 03:11:51 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:11:51 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:11:51 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:11:51 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:11:51 INFO - u'mozbase': [u'target.common.tests.zip'], 03:11:51 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:11:51 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:11:51 INFO - u'web-platform': [u'target.common.tests.zip', 03:11:51 INFO - u'target.web-platform.tests.tar.gz'], 03:11:51 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:11:51 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:11:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.common.tests.zip 03:11:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:11:51 INFO - Fetch https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.common.tests.zip into memory 03:11:52 INFO - Content-Length response header: 54004043 03:11:52 INFO - Bytes received: 54004043 03:12:03 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:12:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.common.tests.zip 03:12:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:12:03 INFO - Fetch https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.common.tests.zip into memory 03:12:05 INFO - Content-Length response header: 54004043 03:12:05 INFO - Bytes received: 54004043 03:12:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.talos.tests.zip 03:12:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:12:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.talos.tests.zip into memory 03:12:17 INFO - Content-Length response header: 14496050 03:12:17 INFO - Bytes received: 14496050 03:12:18 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')]} 03:12:18 INFO - trying https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2 03:12:18 INFO - Downloading https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:12:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:12:19 INFO - Downloaded 61824978 bytes. 03:12:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2 03:12:19 INFO - mkdir: /builds/slave/test/properties 03:12:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:12:19 INFO - Writing to file /builds/slave/test/properties/build_url 03:12:19 INFO - Contents: 03:12:19 INFO - build_url:https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2 03:12:19 INFO - Running post-action listener: _resource_record_post_action 03:12:19 INFO - Running post-action listener: set_extra_try_arguments 03:12:19 INFO - [mozharness: 2017-08-16 10:12:19.269989Z] Finished download-and-extract step (success) 03:12:19 INFO - [mozharness: 2017-08-16 10:12:19.270090Z] Running populate-webroot step. 03:12:19 INFO - Running pre-action listener: _resource_record_pre_action 03:12:19 INFO - Running main action method: populate_webroot 03:12:19 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:12:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:12:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:12:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:12:19 INFO - 'tests': ['damp', 'tps']}, 03:12:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:12:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:12:19 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:12:19 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:12:19 INFO - 'other-e10s': {'tests': ['a11yr', 03:12:19 INFO - 'ts_paint', 03:12:19 INFO - 'tpaint', 03:12:19 INFO - 'sessionrestore', 03:12:19 INFO - 'sessionrestore_many_windows', 03:12:19 INFO - 'sessionrestore_no_auto_restore', 03:12:19 INFO - 'tabpaint']}, 03:12:19 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:12:19 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:12:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:12:19 INFO - 'tsvgr_opacity', 03:12:19 INFO - 'tart', 03:12:19 INFO - 'tscrollx', 03:12:19 INFO - 'tsvg_static']}, 03:12:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:12:19 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:12:19 INFO - 'talos_options': ['--mitmproxy', 03:12:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:12:19 INFO - '--firstNonBlankPaint'], 03:12:19 INFO - 'tests': ['tp6_google', 03:12:19 INFO - 'tp6_youtube', 03:12:19 INFO - 'tp6_amazon', 03:12:19 INFO - 'tp6_facebook']}, 03:12:19 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:12:19 INFO - 'talos_options': ['--stylo', 03:12:19 INFO - '--mitmproxy', 03:12:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:12:19 INFO - '--firstNonBlankPaint'], 03:12:19 INFO - 'tests': ['tp6_google', 03:12:19 INFO - 'tp6_youtube', 03:12:19 INFO - 'tp6_amazon', 03:12:19 INFO - 'tp6_facebook']}, 03:12:19 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:12:19 INFO - 'talos_options': ['--stylo', 03:12:19 INFO - '--stylo-threads=1', 03:12:19 INFO - '--mitmproxy', 03:12:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:12:19 INFO - '--firstNonBlankPaint'], 03:12:19 INFO - 'tests': ['tp6_google', 03:12:19 INFO - 'tp6_youtube', 03:12:19 INFO - 'tp6_amazon', 03:12:19 INFO - 'tp6_facebook']}, 03:12:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:12:19 INFO - 'talos_options': ['--xperf_path', 03:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:12:19 INFO - 'tests': ['tp5n']}}} 03:12:19 INFO - Running post-action listener: _resource_record_post_action 03:12:19 INFO - [mozharness: 2017-08-16 10:12:19.278525Z] Finished populate-webroot step (success) 03:12:19 INFO - [mozharness: 2017-08-16 10:12:19.278616Z] Running create-virtualenv step. 03:12:19 INFO - Running pre-action listener: _resource_record_pre_action 03:12:19 INFO - Running main action method: create_virtualenv 03:12:19 INFO - Creating virtualenv /builds/slave/test/build/venv 03:12:19 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 03:12:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:12:19 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:12:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:12:19 INFO - Using real prefix '/usr' 03:12:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:12:19 INFO - Installing distribute.............................................................................................................................................................................................done. 03:12:21 INFO - Installing pip.................done. 03:12:21 INFO - Return code: 0 03:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:12:22 INFO - Reading from file tmpfile_stdout 03:12:22 INFO - Output received: 03:12:22 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) 03:12:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:12: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')]} 03:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12: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')]} 03:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12: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', 'pip>=1.5'] in /builds/slave/test/build 03:12: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 pip>=1.5 03:12:22 INFO - Using env: {'DISPLAY': ':0', 03:12:22 INFO - 'HOME': '/home/cltbld', 03:12:22 INFO - 'LANG': 'en_US.UTF-8', 03:12:22 INFO - 'LANGUAGE': 'en_US:en', 03:12:22 INFO - 'LOGNAME': 'cltbld', 03:12:22 INFO - 'MAIL': '/var/mail/cltbld', 03:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:22 INFO - 'MOZ_NO_REMOTE': '1', 03:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:22 INFO - 'NO_EM_RESTART': '1', 03:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:22 INFO - 'PWD': '/builds/slave/test', 03:12:22 INFO - 'SHELL': '/bin/bash', 03:12:22 INFO - 'SHLVL': '1', 03:12:22 INFO - 'TERM': 'linux', 03:12:22 INFO - 'TMOUT': '86400', 03:12:22 INFO - 'USER': 'cltbld', 03:12:22 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:22 INFO - '_': '/tools/buildbot/bin/python'} 03:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:22 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 03:12:22 INFO - Cleaning up... 03:12:22 INFO - Return code: 0 03:12:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:12: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')]} 03:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12: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')]} 03:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12: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', '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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:12: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 psutil>=3.1.1 03:12:22 INFO - Using env: {'DISPLAY': ':0', 03:12:22 INFO - 'HOME': '/home/cltbld', 03:12:22 INFO - 'LANG': 'en_US.UTF-8', 03:12:22 INFO - 'LANGUAGE': 'en_US:en', 03:12:22 INFO - 'LOGNAME': 'cltbld', 03:12:22 INFO - 'MAIL': '/var/mail/cltbld', 03:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:22 INFO - 'MOZ_NO_REMOTE': '1', 03:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:22 INFO - 'NO_EM_RESTART': '1', 03:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:22 INFO - 'PWD': '/builds/slave/test', 03:12:22 INFO - 'SHELL': '/bin/bash', 03:12:22 INFO - 'SHLVL': '1', 03:12:22 INFO - 'TERM': 'linux', 03:12:22 INFO - 'TMOUT': '86400', 03:12:22 INFO - 'USER': 'cltbld', 03:12:22 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:22 INFO - '_': '/tools/buildbot/bin/python'} 03:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:22 INFO - Downloading/unpacking psutil>=3.1.1 03:12:22 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 03:12:22 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 03:12:22 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 03:12:22 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 03:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:12:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:12:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:12:23 INFO - Installing collected packages: psutil 03:12:23 INFO - Running setup.py install for psutil 03:12:23 INFO - building 'psutil._psutil_linux' extension 03:12:23 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 03:12:23 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 03:12:24 INFO - building 'psutil._psutil_posix' extension 03:12:24 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 03:12:24 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 03:12:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:12:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:12:24 INFO - Successfully installed psutil 03:12:24 INFO - Cleaning up... 03:12:24 INFO - Return code: 0 03:12:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:12:24 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')]} 03:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:24 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')]} 03:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:24 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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:24 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 03:12:24 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 03:12:24 INFO - Using env: {'DISPLAY': ':0', 03:12:24 INFO - 'HOME': '/home/cltbld', 03:12:24 INFO - 'LANG': 'en_US.UTF-8', 03:12:24 INFO - 'LANGUAGE': 'en_US:en', 03:12:24 INFO - 'LOGNAME': 'cltbld', 03:12:24 INFO - 'MAIL': '/var/mail/cltbld', 03:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:24 INFO - 'MOZ_NO_REMOTE': '1', 03:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:24 INFO - 'NO_EM_RESTART': '1', 03:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:24 INFO - 'PWD': '/builds/slave/test', 03:12:24 INFO - 'SHELL': '/bin/bash', 03:12:24 INFO - 'SHLVL': '1', 03:12:24 INFO - 'TERM': 'linux', 03:12:24 INFO - 'TMOUT': '86400', 03:12:24 INFO - 'USER': 'cltbld', 03:12:24 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:24 INFO - '_': '/tools/buildbot/bin/python'} 03:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:12:24 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 03:12:24 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 03:12:24 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 03:12:24 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 03:12:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:12:25 INFO - Installing collected packages: mozsystemmonitor 03:12:25 INFO - Running setup.py install for mozsystemmonitor 03:12:25 INFO - Successfully installed mozsystemmonitor 03:12:25 INFO - Cleaning up... 03:12:25 INFO - Return code: 0 03:12:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:12:25 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')]} 03:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:25 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')]} 03:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:25 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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:25 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 03:12:25 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 03:12:25 INFO - Using env: {'DISPLAY': ':0', 03:12:25 INFO - 'HOME': '/home/cltbld', 03:12:25 INFO - 'LANG': 'en_US.UTF-8', 03:12:25 INFO - 'LANGUAGE': 'en_US:en', 03:12:25 INFO - 'LOGNAME': 'cltbld', 03:12:25 INFO - 'MAIL': '/var/mail/cltbld', 03:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:25 INFO - 'MOZ_NO_REMOTE': '1', 03:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:25 INFO - 'NO_EM_RESTART': '1', 03:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:25 INFO - 'PWD': '/builds/slave/test', 03:12:25 INFO - 'SHELL': '/bin/bash', 03:12:25 INFO - 'SHLVL': '1', 03:12:25 INFO - 'TERM': 'linux', 03:12:25 INFO - 'TMOUT': '86400', 03:12:25 INFO - 'USER': 'cltbld', 03:12:25 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:25 INFO - '_': '/tools/buildbot/bin/python'} 03:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:25 INFO - Downloading/unpacking jsonschema==2.5.1 03:12:25 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 03:12:25 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 03:12:25 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 03:12:25 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 03:12:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:12:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:27 INFO - Downloading functools32-3.2.3-2.tar.gz 03:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:12:27 INFO - warning: no files found matching '*.txt' 03:12:27 INFO - no previously-included directories found matching 'build' 03:12:27 INFO - no previously-included directories found matching 'dist' 03:12:27 INFO - no previously-included directories found matching '.git*' 03:12:27 INFO - Installing collected packages: jsonschema, functools32 03:12:27 INFO - Running setup.py install for functools32 03:12:27 INFO - warning: no files found matching '*.txt' 03:12:27 INFO - no previously-included directories found matching 'build' 03:12:27 INFO - no previously-included directories found matching 'dist' 03:12:27 INFO - no previously-included directories found matching '.git*' 03:12:27 INFO - Successfully installed jsonschema functools32 03:12:27 INFO - Cleaning up... 03:12:27 INFO - Return code: 0 03:12:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:12:27 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')]} 03:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:27 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')]} 03:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:27 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 03:12:27 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 03:12:27 INFO - Using env: {'DISPLAY': ':0', 03:12:27 INFO - 'HOME': '/home/cltbld', 03:12:27 INFO - 'LANG': 'en_US.UTF-8', 03:12:27 INFO - 'LANGUAGE': 'en_US:en', 03:12:27 INFO - 'LOGNAME': 'cltbld', 03:12:27 INFO - 'MAIL': '/var/mail/cltbld', 03:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:27 INFO - 'MOZ_NO_REMOTE': '1', 03:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:27 INFO - 'NO_EM_RESTART': '1', 03:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:27 INFO - 'PWD': '/builds/slave/test', 03:12:27 INFO - 'SHELL': '/bin/bash', 03:12:27 INFO - 'SHLVL': '1', 03:12:27 INFO - 'TERM': 'linux', 03:12:27 INFO - 'TMOUT': '86400', 03:12:27 INFO - 'USER': 'cltbld', 03:12:27 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:27 INFO - '_': '/tools/buildbot/bin/python'} 03:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:12:27 INFO - Cleaning up... 03:12:27 INFO - Return code: 0 03:12:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:12:27 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')]} 03:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:27 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')]} 03:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:27 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 03:12:27 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 03:12:27 INFO - Using env: {'DISPLAY': ':0', 03:12:27 INFO - 'HOME': '/home/cltbld', 03:12:27 INFO - 'LANG': 'en_US.UTF-8', 03:12:27 INFO - 'LANGUAGE': 'en_US:en', 03:12:27 INFO - 'LOGNAME': 'cltbld', 03:12:27 INFO - 'MAIL': '/var/mail/cltbld', 03:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:27 INFO - 'MOZ_NO_REMOTE': '1', 03:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:27 INFO - 'NO_EM_RESTART': '1', 03:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:27 INFO - 'PWD': '/builds/slave/test', 03:12:27 INFO - 'SHELL': '/bin/bash', 03:12:27 INFO - 'SHLVL': '1', 03:12:27 INFO - 'TERM': 'linux', 03:12:27 INFO - 'TMOUT': '86400', 03:12:27 INFO - 'USER': 'cltbld', 03:12:27 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:27 INFO - '_': '/tools/buildbot/bin/python'} 03:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:27 INFO - Downloading/unpacking blobuploader==1.2.4 03:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:28 INFO - Downloading blobuploader-1.2.4.tar.gz 03:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:12:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:12:29 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 03:12:29 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 03:12:29 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 03:12:29 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 03:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:12:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:12:29 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 03:12:29 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 03:12:29 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 03:12:29 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 03:12:29 INFO - Downloading docopt-0.6.1.tar.gz 03:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:12:29 INFO - Installing collected packages: blobuploader, requests, docopt 03:12:29 INFO - Running setup.py install for blobuploader 03:12:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:12:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:12:29 INFO - Running setup.py install for requests 03:12:30 INFO - Running setup.py install for docopt 03:12:30 INFO - Successfully installed blobuploader requests docopt 03:12:30 INFO - Cleaning up... 03:12:30 INFO - Return code: 0 03:12:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:12: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')]} 03:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12: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')]} 03:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:30 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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:30 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 03:12:30 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 03:12:30 INFO - Using env: {'DISPLAY': ':0', 03:12:30 INFO - 'HOME': '/home/cltbld', 03:12:30 INFO - 'LANG': 'en_US.UTF-8', 03:12:30 INFO - 'LANGUAGE': 'en_US:en', 03:12:30 INFO - 'LOGNAME': 'cltbld', 03:12:30 INFO - 'MAIL': '/var/mail/cltbld', 03:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:30 INFO - 'MOZ_NO_REMOTE': '1', 03:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:30 INFO - 'NO_EM_RESTART': '1', 03:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:30 INFO - 'PWD': '/builds/slave/test', 03:12:30 INFO - 'SHELL': '/bin/bash', 03:12:30 INFO - 'SHLVL': '1', 03:12:30 INFO - 'TERM': 'linux', 03:12:30 INFO - 'TMOUT': '86400', 03:12:30 INFO - 'USER': 'cltbld', 03:12:30 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:30 INFO - '_': '/tools/buildbot/bin/python'} 03:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:12:30 INFO - Running setup.py (path:/tmp/pip-S2A6rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:12:30 INFO - Running setup.py (path:/tmp/pip-rHe3U7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:12:30 INFO - Running setup.py (path:/tmp/pip-bSiiLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:12:30 INFO - Running setup.py (path:/tmp/pip-0JTG5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:12:30 INFO - Running setup.py (path:/tmp/pip-FYDlbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:12:30 INFO - Running setup.py (path:/tmp/pip-504y0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:12:31 INFO - Running setup.py (path:/tmp/pip-2NR5km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:12:31 INFO - Running setup.py (path:/tmp/pip-pN1F1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:12:31 INFO - Running setup.py (path:/tmp/pip-9znmIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:12:31 INFO - Running setup.py (path:/tmp/pip-XLx9Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:12:31 INFO - Running setup.py (path:/tmp/pip-eGPBja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:12:31 INFO - Running setup.py (path:/tmp/pip-5xGS6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:12:31 INFO - Running setup.py (path:/tmp/pip-njLFOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:12:31 INFO - Running setup.py (path:/tmp/pip-KOqypY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:12:31 INFO - Running setup.py (path:/tmp/pip-Kg57he-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:12:31 INFO - Running setup.py (path:/tmp/pip-Wu8Ryx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:12:31 INFO - Running setup.py (path:/tmp/pip-vfpvYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:12:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:12:31 INFO - Running setup.py install for manifestparser 03:12:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:12:31 INFO - Running setup.py install for mozcrash 03:12:32 INFO - Running setup.py install for mozdebug 03:12:32 INFO - Running setup.py install for mozdevice 03:12:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Running setup.py install for mozfile 03:12:32 INFO - Running setup.py install for mozhttpd 03:12:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Running setup.py install for mozinfo 03:12:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Running setup.py install for mozInstall 03:12:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Running setup.py install for mozleak 03:12:32 INFO - Running setup.py install for mozlog 03:12:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Running setup.py install for moznetwork 03:12:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:12:32 INFO - Running setup.py install for mozprocess 03:12:33 INFO - Running setup.py install for mozprofile 03:12:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:12:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:12:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:12:33 INFO - Running setup.py install for mozrunner 03:12:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:12:33 INFO - Running setup.py install for mozscreenshot 03:12:33 INFO - Running setup.py install for moztest 03:12:33 INFO - Running setup.py install for mozversion 03:12:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:12:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:12:33 INFO - Cleaning up... 03:12:33 INFO - Return code: 0 03:12:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:12:33 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')]} 03:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:33 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')]} 03:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:33 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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:33 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 03:12:33 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 03:12:33 INFO - Using env: {'DISPLAY': ':0', 03:12:33 INFO - 'HOME': '/home/cltbld', 03:12:33 INFO - 'LANG': 'en_US.UTF-8', 03:12:33 INFO - 'LANGUAGE': 'en_US:en', 03:12:33 INFO - 'LOGNAME': 'cltbld', 03:12:33 INFO - 'MAIL': '/var/mail/cltbld', 03:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:33 INFO - 'MOZ_NO_REMOTE': '1', 03:12:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:33 INFO - 'NO_EM_RESTART': '1', 03:12:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:33 INFO - 'PWD': '/builds/slave/test', 03:12:33 INFO - 'SHELL': '/bin/bash', 03:12:33 INFO - 'SHLVL': '1', 03:12:33 INFO - 'TERM': 'linux', 03:12:33 INFO - 'TMOUT': '86400', 03:12:33 INFO - 'USER': 'cltbld', 03:12:33 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:33 INFO - '_': '/tools/buildbot/bin/python'} 03:12:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:12:33 INFO - Running setup.py (path:/tmp/pip-xUHRaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:12:33 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)) 03:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:12:33 INFO - Running setup.py (path:/tmp/pip-2I44J0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:12:33 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)) 03:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:12:33 INFO - Running setup.py (path:/tmp/pip-FR4xa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:12:34 INFO - Running setup.py (path:/tmp/pip-086B6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:12:34 INFO - Running setup.py (path:/tmp/pip-zgfLkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:12:34 INFO - Running setup.py (path:/tmp/pip-YF1Ilm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:12:34 INFO - Running setup.py (path:/tmp/pip-Rm_prB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:12:34 INFO - Running setup.py (path:/tmp/pip-9UtzBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:12:34 INFO - Running setup.py (path:/tmp/pip-b6sJdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:12:34 INFO - Running setup.py (path:/tmp/pip-9_Lv5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:12:34 INFO - Running setup.py (path:/tmp/pip-q4HJm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:12:34 INFO - Running setup.py (path:/tmp/pip-JtKCm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:12:34 INFO - Running setup.py (path:/tmp/pip-Z4ySH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:12:34 INFO - Running setup.py (path:/tmp/pip-N9zmua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:12:34 INFO - Running setup.py (path:/tmp/pip-_4UpSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:12:34 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)) 03:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:12:34 INFO - Running setup.py (path:/tmp/pip-3xcyS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:12:35 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)) 03:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:12:35 INFO - Running setup.py (path:/tmp/pip-uhv3PW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:12:35 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)) 03:12:35 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)) 03:12:35 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)) 03:12:35 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)) 03:12:35 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)) 03:12:35 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)) 03:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:12:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:36 INFO - Downloading blessings-1.6.tar.gz 03:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:12:36 INFO - Installing collected packages: blessings 03:12:36 INFO - Running setup.py install for blessings 03:12:36 INFO - Successfully installed blessings 03:12:36 INFO - Cleaning up... 03:12:36 INFO - Return code: 0 03:12:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:12:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:12:36 INFO - Reading from file tmpfile_stdout 03:12:36 INFO - Current package versions: 03:12:36 INFO - argparse == 1.2.1 03:12:36 INFO - blessings == 1.6 03:12:36 INFO - blobuploader == 1.2.4 03:12:36 INFO - docopt == 0.6.1 03:12:36 INFO - functools32 == 3.2.3-2 03:12:36 INFO - jsonschema == 2.5.1 03:12:36 INFO - manifestparser == 1.1 03:12:36 INFO - mozInstall == 1.13 03:12:36 INFO - mozcrash == 1.0 03:12:36 INFO - mozdebug == 0.1 03:12:36 INFO - mozdevice == 0.51 03:12:36 INFO - mozfile == 1.2 03:12:36 INFO - mozhttpd == 0.7 03:12:36 INFO - mozinfo == 0.10 03:12:36 INFO - mozleak == 0.1 03:12:36 INFO - mozlog == 3.5 03:12:36 INFO - moznetwork == 0.27 03:12:36 INFO - mozprocess == 0.25 03:12:36 INFO - mozprofile == 0.28 03:12:36 INFO - mozrunner == 6.13 03:12:36 INFO - mozscreenshot == 0.1 03:12:36 INFO - mozsystemmonitor == 0.3 03:12:36 INFO - moztest == 0.8 03:12:36 INFO - mozversion == 1.4 03:12:36 INFO - psutil == 3.1.1 03:12:36 INFO - requests == 1.2.3 03:12:36 INFO - wsgiref == 0.1.2 03:12:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:36 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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:36 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 03:12:36 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 03:12:36 INFO - Using env: {'DISPLAY': ':0', 03:12:36 INFO - 'HOME': '/home/cltbld', 03:12:36 INFO - 'LANG': 'en_US.UTF-8', 03:12:36 INFO - 'LANGUAGE': 'en_US:en', 03:12:36 INFO - 'LOGNAME': 'cltbld', 03:12:36 INFO - 'MAIL': '/var/mail/cltbld', 03:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:36 INFO - 'MOZ_NO_REMOTE': '1', 03:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:36 INFO - 'NO_EM_RESTART': '1', 03:12:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:36 INFO - 'PWD': '/builds/slave/test', 03:12:36 INFO - 'SHELL': '/bin/bash', 03:12:36 INFO - 'SHLVL': '1', 03:12:36 INFO - 'TERM': 'linux', 03:12:36 INFO - 'TMOUT': '86400', 03:12:36 INFO - 'USER': 'cltbld', 03:12:36 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:36 INFO - '_': '/tools/buildbot/bin/python'} 03:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 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)) 03:12:36 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:12:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:12:38 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)) 03:12:38 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)) 03:12:38 INFO - Installing collected packages: simplejson 03:12:38 INFO - Running setup.py install for simplejson 03:12:38 INFO - building 'simplejson._speedups' extension 03:12:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:12:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:12:39 INFO - Successfully installed simplejson 03:12:39 INFO - Cleaning up... 03:12:39 INFO - Return code: 0 03:12:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:12:39 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')]} 03:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:12:39 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')]} 03:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:12:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1201a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, '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': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', '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 03:12:39 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 03:12:39 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 03:12:39 INFO - Using env: {'DISPLAY': ':0', 03:12:39 INFO - 'HOME': '/home/cltbld', 03:12:39 INFO - 'LANG': 'en_US.UTF-8', 03:12:39 INFO - 'LANGUAGE': 'en_US:en', 03:12:39 INFO - 'LOGNAME': 'cltbld', 03:12:39 INFO - 'MAIL': '/var/mail/cltbld', 03:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:39 INFO - 'MOZ_NO_REMOTE': '1', 03:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:39 INFO - 'NO_EM_RESTART': '1', 03:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:39 INFO - 'PWD': '/builds/slave/test', 03:12:39 INFO - 'SHELL': '/bin/bash', 03:12:39 INFO - 'SHLVL': '1', 03:12:39 INFO - 'TERM': 'linux', 03:12:39 INFO - 'TMOUT': '86400', 03:12:39 INFO - 'USER': 'cltbld', 03:12:39 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:39 INFO - '_': '/tools/buildbot/bin/python'} 03:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:12:39 INFO - Cleaning up... 03:12:39 INFO - Return code: 0 03:12:39 INFO - Running post-action listener: _resource_record_post_action 03:12:39 INFO - Running post-action listener: _start_resource_monitoring 03:12:39 INFO - Starting resource monitoring. 03:12:39 INFO - [mozharness: 2017-08-16 10:12:39.370091Z] Finished create-virtualenv step (success) 03:12:39 INFO - [mozharness: 2017-08-16 10:12:39.370600Z] Running install step. 03:12:39 INFO - Running pre-action listener: _resource_record_pre_action 03:12:39 INFO - Running main action method: install 03:12:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:12:39 INFO - Reading from file tmpfile_stdout 03:12:39 INFO - Detecting whether we're running mozinstall >=1.0... 03:12:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:12:39 INFO - Reading from file tmpfile_stdout 03:12:39 INFO - Output received: 03:12:39 INFO - Usage: mozinstall [options] installer 03:12:39 INFO - Options: 03:12:39 INFO - -h, --help show this help message and exit 03:12:39 INFO - -d DEST, --destination=DEST 03:12:39 INFO - Directory to install application into. [default: 03:12:39 INFO - "/builds/slave/test"] 03:12:39 INFO - --app=APP Application being installed. [default: firefox] 03:12:39 INFO - mkdir: /builds/slave/test/build/application 03:12:39 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'] 03:12:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:12:57 INFO - Reading from file tmpfile_stdout 03:12:57 INFO - Output received: 03:12:57 INFO - /builds/slave/test/build/application/firefox/firefox 03:12:57 INFO - Running post-action listener: _resource_record_post_action 03:12:57 INFO - [mozharness: 2017-08-16 10:12:57.636589Z] Finished install step (success) 03:12:57 INFO - [mozharness: 2017-08-16 10:12:57.636871Z] Running setup-mitmproxy step. 03:12:57 INFO - Running pre-action listener: _resource_record_pre_action 03:12:57 INFO - Running main action method: setup_mitmproxy 03:12:57 INFO - Skipping: mitmproxy is not required 03:12:57 INFO - Running post-action listener: _resource_record_post_action 03:12:57 INFO - [mozharness: 2017-08-16 10:12:57.638134Z] Finished setup-mitmproxy step (success) 03:12:57 INFO - [mozharness: 2017-08-16 10:12:57.638356Z] Running run-tests step. 03:12:57 INFO - Running pre-action listener: _resource_record_pre_action 03:12:57 INFO - Running pre-action listener: _set_gcov_prefix 03:12:57 INFO - Running main action method: run_tests 03:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:12:57 INFO - Python 2.7.3 03:12:57 INFO - Return code: 0 03:12:57 INFO - grabbing minidump binary from tooltool 03:12:57 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')]} 03:12:57 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 0x13a20b0>, '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 0x13a2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a3440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:12:57 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 03:12:57 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 03:12:57 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 03:12:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:12:57 INFO - Return code: 0 03:12:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:12:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:12:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:12:57 INFO - ENV: RUST_BACKTRACE is now full 03:12:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:12:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:12:57 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/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:12:57 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/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 03:12:57 INFO - Using env: {'DISPLAY': ':0', 03:12:57 INFO - 'HOME': '/home/cltbld', 03:12:57 INFO - 'LANG': 'en_US.UTF-8', 03:12:57 INFO - 'LANGUAGE': 'en_US:en', 03:12:57 INFO - 'LOGNAME': 'cltbld', 03:12:57 INFO - 'MAIL': '/var/mail/cltbld', 03:12:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:12:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:12:57 INFO - 'MOZ_NO_REMOTE': '1', 03:12:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:12:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:12:57 INFO - 'NO_EM_RESTART': '1', 03:12:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:12:57 INFO - 'PWD': '/builds/slave/test', 03:12:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:12:57 INFO - 'RUST_BACKTRACE': 'full', 03:12:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:12:57 INFO - 'SHELL': '/bin/bash', 03:12:57 INFO - 'SHLVL': '1', 03:12:57 INFO - 'STYLO_FORCE_ENABLED': '1', 03:12:57 INFO - 'TERM': 'linux', 03:12:57 INFO - 'TMOUT': '86400', 03:12:57 INFO - 'USER': 'cltbld', 03:12:57 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978', 03:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:12:57 INFO - '_': '/tools/buildbot/bin/python'} 03:12:57 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/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:12:58 INFO - mozversion application_buildid: 20170816091812 03:12:58 INFO - mozversion application_changeset: c498777e8f39a3027e44d7badad05bd3a3f86f08 03:12:58 INFO - mozversion application_display_name: Nightly 03:12:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:12:58 INFO - mozversion application_name: Firefox 03:12:58 INFO - mozversion application_remotingname: firefox 03:12:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:12:58 INFO - mozversion application_vendor: Mozilla 03:12:58 INFO - mozversion application_version: 57.0a1 03:12:58 INFO - mozversion platform_buildid: 20170816091812 03:12:58 INFO - mozversion platform_changeset: c498777e8f39a3027e44d7badad05bd3a3f86f08 03:12:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:12:58 INFO - mozversion platform_version: 57.0a1 03:12:58 INFO - using testdate: 1502878378 03:12:58 INFO - actual date: 1502878378 03:12:58 INFO - starting webserver on 'localhost:55325' 03:12:58 INFO - SUITE-START | Running 2 tests 03:12:58 INFO - TEST-START | dromaeo_css 03:12:58 INFO - Initialising browser for dromaeo_css test... 03:12:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55325/getInfo.html -profile /tmp/tmpSOBi6t/profile 03:12:58 INFO - TEST-INFO | started process 2084 (/builds/slave/test/build/application/firefox/firefox http://localhost:55325/getInfo.html) 03:13:04 INFO - TEST-INFO | 2084: exit 0 03:13:04 INFO - Browser initialized. 03:13:04 INFO - Running cycle 1/1 for dromaeo_css test... 03:13:04 INFO - TEST-INFO | started process 2315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSOBi6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:13:05 INFO - PID 2315 | 03:13:05 INFO - PID 2315 | (/builds/slave/test/build/application/firefox/firefox:2369): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:13:05 INFO - PID 2315 | 03:13:15 INFO - PID 2315 | RSS: Main: 167604224 03:13:15 INFO - PID 2315 | 03:16:15 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:55325/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55325/tests/dromaeo/cssquery-ext.html) 03:16:16 INFO - PID 2315 | RSS: Main: 175157248 03:16:16 INFO - PID 2315 | 03:19:18 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:55325/tests/dromaeo/cssquery-ext.html (next: http://localhost:55325/tests/dromaeo/cssquery-jquery.html) 03:19:19 INFO - PID 2315 | RSS: Main: 172068864 03:19:19 INFO - PID 2315 | 03:22:20 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:55325/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55325/tests/dromaeo/cssquery-mootools.html) 03:22:21 INFO - PID 2315 | RSS: Main: 174370816 03:22:21 INFO - PID 2315 | 03:25:23 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:55325/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55325/tests/dromaeo/cssquery-prototype.html) 03:25:24 INFO - PID 2315 | RSS: Main: 172781568 03:25:24 INFO - PID 2315 | 03:28:27 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:55325/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55325/tests/dromaeo/cssquery-yui.html) 03:28:28 INFO - PID 2315 | RSS: Main: 176316416 03:28:28 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:55325/tests/dromaeo/cssquery-yui.html (next: http://localhost:55325/tests/dromaeo/cssquery-dojo.html) 03:31:30 INFO - PID 2315 | RSS: Main: 177115136 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | __start_tp_report 03:31:30 INFO - PID 2315 | _x_x_mozilla_page_load 03:31:30 INFO - PID 2315 | _x_x_mozilla_page_load_details 03:31:30 INFO - PID 2315 | |i|pagename|runs| 03:31:30 INFO - PID 2315 | |0;dojo.html;3136.357046805405;3179.062176657886;3187.5537424760537;3195.217171792911;3221.468457704479;6194.318624951257;6196.13254144419;6222.595531290469;6232.781852635158;6270.623762574249;6220.6267623942595;6224.96887515563;6337.968310158454;6351.23785145782;6367.554271201017;5750.597458177908;5787.421257874221;5797.565182611311;5851.385604511529;5870.090136028901;5626.212330273765;5651.745671444774;5762.135679648044;5772.5670574706855;5772.769089236425;5665.971670141644;5686.516646085088;5741.655500669973;5802.274715660543;5803.0134877071;5710.314762228559;5715.799947001877;5777.826665200051;5785.2189954356045;5787.536997040227;5512.476314750093;5667.376588575295;5743.10981797822;5748.367679555246;5765.798197063083;6080.361562035967;6096.664683442409;6109.816705498843;6141.662208578527;6155.138280640714;6761.695723692445;6788.355106264897;6802.727890884359;6808.455323574102;6822.4200942919915;6770.119884414995;6910.2398736139485;6922.06552115463;6923.4807389446;6926.688299026556;6196.349383314732;6230.813075607739;6261.8747625047245;6330.335314791972;6416.358364163546;6726.293739157416;6874.518783685094;6899.206591242071;6921.411680007155;6921.757738479229;6452.935470645328;6527.67361631926;6535.967320163464;6544.9672751635935;6563.409293163638;6781.62701051437;6801.4898882584;6803.795886123424;6805.727770889109;6809.285025072395;6763.6618169092335;6774.627395493295;6774.898376524356;6816.7614133505085;6839.521233513607;6676.098726671934;6756.797296081126;6765.830854228684;6776.457883369319;6783.050372947791;5437.02133615953;5454.290942177491;5492.340919089655;5521.199426083195;5527.198556209327;4566.748828814447;4624.815007399667;4653.697509661861;4672.672912896064;4692.178868698033;5831.9708401458565;5836.06622940336;5849.532037436995;5852.853678658068;5870.471657550841;6701.4921642630015;6782.2200446948245;6805.863882722318;6807.4894382921475;6814.773340798604;5781.132830075522;5801.3328467225965;5844.357120716718;5864.472197502245;5894.17481552583;5809.535237181015;5866.794662186803;5898.174255604219;5902.61632993854;5962.672053037038;6466.838329041811;6474.996375561701;6528.575642583217;6530.542861999709;6532.836679082966;6619.966900165661;6646;6690.13028306317;6700;6714.026466162281;5642.266505354278;6431.453326467303;6439.45264652491;6476.190476190476;6655.900161497679;6626.138601981712;6626.56927299724;6654.401103900672;6724.260331363448;6764.864702706017;6672.599644021374;6803.829904252433;6808.046873437624;6810.284920083518;6857.588544687334;6517.869642607026;6531.477481801349;6539.901901471577;6540.803775886731;6540.934590654033;6170.969145154246;6535.509836762167;6539.607623542603;6540.215174179129;6550.181227346582;5922.2597175353085;6436.613803171825;6463.838404039937;6488.318726533835;6508.056331831764;6575.309592492719;6629.701663425061;6634.568753031038;6663.36698013688;6667.49993750461;6372.9362706374195;6379.330170332234;6462.4183823456115;6469;6477.255115661759 03:31:30 INFO - PID 2315 | |1;ext.html;807.2734538914975;845.2350622686463;861.0614430271005;888.6178943054485;892.4466830565051;41426;42767.14465710684;42771.14457710844;42787.35818962714;42843.57156428435;37868.43197352035;38167.427488587695;38189.42715859264;38239.4264086039;38331.425028624595;6731.461483081354;6840;6849.897251541206;6851;6853.931460685404;6747.831304217385;6835.589864608114;6856.657167141648;6860.656967151647;6868.175818901739;7388.852222955537;7568.697252109909;7576.734814281501;7580.886286705704;7581.3934885209055;7440.069991251094;7527.20964298749;7528.1719010908755;7542.58515781629;7546.962265188639;7293.234210407883;7328.633568321616;7484.6631901564015;7535.171131175566;7536.585487798169;38580.80709596462;38619.8414047579;38745.41881871774;38838.61161388378;38986.22027559461;3458.6887180153653;3473.478978153272;3504.106452854531;3505.7545971781988;3509.4034014217646;30346.393072138653;30432.391352173054;30615.38769224625;30685.386292274252;30717.539236911463;4943.456219815817;4970.428400733889;4981;4998;5010.874728131789;180628;182378;186599;187749;195698;48936.26595601069;49142;49200.75399623051;49219.26171107436;49275;49815.75092124589;49824.50175498199;50006.24990625143;50139;50270.748646256536;15289.847101528841;15395.307211175525;15413.9229303855;15742;15887.602809929613;11942.940285298519;11950.163488555718;11964.341961192218;11972.940135299443;11988.580399685969;14370.640733981734;14680.486182983757;14776.852231477547;14869.553913382615;15029.549113526611;5860.8534786629825;5939.46544810969;5972.731227094791;5985.491233245136;6109;990.6131416017633;993.9155171810332;1025.8512515685184;1027.024326889443;1027.7482016905756;23095.88452057729;29078.709212908023;29080.563791543143;29873.10380688583;30080.849595751883;1087.7443800706828;1104.3705088099866;1107.534835369147;1112.5883423133491;1118.373710721982;12544.996400288137;12799.552015679406;12908.741825163632;13065.281409522377;13126.671833204246;233035;234469;234827;235368;235376;54189.72905135528;54516.18225726617;54655.453445465835;54666;54681.72659136679;1420.850810664886;1499.9400023999135;1516.651170230853;1518.2560545332708;1522.0867479512203;23641;23794.286171414886;23877.641835372135;23908.880455598308;24050.51898962046;39838.80080599579;40192.39711404273;40224;40257.39613905735;40301.19397611973;209419;210582;214712;217085;218209;33920.830395847865;34708.13229669177;34824.12939676528;37637.435438468994;37693.05767355838;45790;46792.766036170964;47002;47024.059518810114;47389.28916066193;34352.656473434945;34457.655423445445;34730.82634586912;34907.93511617663;35044.29911401809;33463.33073338569;34206.14484637904;34665.65334346624;34700.82649586736;34780;36960.26079478342;37071.62928370682;37390.25219495649;37512.62487375201;37812.43281350837;32705.672943271216;33252.33495330128;34422.31155376929;35553.933381998584;36073;25426.110086146895;25842;26320.9471621133;26362;26502.734972650025 03:31:30 INFO - PID 2315 | |2;jquery.html;519.4441947116585;537.0628253697299;553.4603761332698;565.7454145634464;574.3280361976489;21459.67810482842;22111.336659900207;22146.44633884154;22161.44596385091;22173.999999999978;24604;25807.61288580668;26786.19641410761;27003;27035.594466082974;6434.420902118808;6608.405243528082;6610.2067751869845;6612.735490580371;6615.603063816162;5945.672987985659;6447.935520644804;6484.319146489622;6503.674816259192;6505.024246363036;6434.839129021765;6474.643894585796;6474.870502589945;6479.773207937723;6482.416582507573;6409.230892292908;6410.519211059166;6414.390632889868;6439.549231553793;6452.709628066725;6013.338532761392;6032.5173986081445;6040.12418199363;6040.879182416371;6041.909371359389;28165;29277;29621.407571848442;29846.253843653863;29857.850710746523;1926.951826204342;1982.7025946108056;1982.7224188613607;1983.5338695406567;1985.8907760073191;18948.62102757937;19250.903745481184;19326.323578674677;19326.516837079045;19518.31685890987;2383.642453631952;2427.599446091393;2431.234161239204;2440.6217036359394;2456.6192240202795;135368;143282.99999999895;143307;143584;143855;14768.926155369369;14977.625559360886;14980.250987450583;15235.466758663393;15655.37378504847;23339;23370.7662923372;23423.297301080995;23438.765612344;23504.7649523506;10246.948765256275;10384.480775961172;10398.168146548258;10455.320404173704;10630;9621.51892405377;9704.126628603459;9768.21854251658;9846.901530984598;9890.752731181778;8967.820643587092;9291.76770580741;9322.906770932339;9701.660441884642;9849.310548261696;2557.7825884799627;2618.3192369983562;2697.986510067437;2709.539378305693;2768.179911721811;940.8682784410188;978.9265805064649;986.3490096536252;990.6186118344489;997.800439912006;19340.806591934183;19394.903025484782;19857.304994325128;20289.391318260477;20374.694379584318;1036.8807587127426;1041.271110222847;1045.9895401046044;1051.2588625019496;1052.8683914510686;10948.56205751761;11144.60993865211;11180.776384472265;11220.158488113424;11339.943300283447;426774;452375;459941;466925;469473;118368;121442.39278803549;123063.38468307602;127834;128719.35640321739;1219.804831227017;1248.6004478566772;1301.8502872169822;1311.7179806341874;1311.895048396126;20933.058012389174;21059;21214;21245.256416025364;21380.78619213788;58998.705006474695;60404;61971;62373.68813155905;62678.6866065685;76178;78275.21724782679;79972.60013699894;80330.19669803228;81956;11474.655760327203;13192.20846749198;14611.415543378145;15087.170205638356;15107.564781345754;23883.761162388848;23923.641145382484;24050.639240411758;24125.51748964976;24275.878620606785;18504.1673124707;18559;18701.158447869606;18834.434966951012;18880.52798679989;16869.91565042167;17134.40029599008;17399.652006960045;17746.64506709833;17813.376531821323;19029.524261893563;19031.90484047626;19882.50293742668;19945;19999.600007999466;14669.92665036668;14886.851131488547;15024.6995060096;15046.623834404227;15055.096694198382;18379.81620183781;18971;19216.423507294803;19364.322248721226;19460 03:31:30 INFO - PID 2315 | |3;mootools.html;1388.278095390397;1442.0554536778407;1450.572081236035;1451.3323871019331;1452.2012892908897;14132.858671413283;14760;14765.557033289018;14778.482753103644;14787;14263.286835658202;15284.159371234606;15296.31166597503;15314.387424503006;15329.156896370696;2740.7122252163535;2785.220138361252;2790.6511686039244;2794.147784925597;2794.273488892892;2717.076194094008;2745.862706864659;2808.4383123375305;2814.2542226310043;2825.4490374377006;2516.421223118684;2713.905013324534;2774.7641450476735;2779.4580056888913;2789.065663002886;2594.2995391244353;2598.4803039392104;2621.7640412362884;2653.2968763277745;2666.013574977251;2642.101685426964;2644.801639877017;2660.0955675070377;2662.720414356484;2663.427363116939;13977.301134943315;14356.569302920929;14385.064970776866;14390.496332628307;14416.495422660157;7625.466217364787;7893.447458677895;7893.960530197312;7896;7897.407694422884;4954.007689300775;5093.1087059764395;5196.948030519684;5215.713135777531;5216.139337009389;1935.5935253596622;1936.9225230990744;1939.5345117171778;1959.6962470817045;1990.8108729670657;23781;23918;23940;23950.64074038891;23959.401014974766;6490.2536208335705;6557.409833115043;6625.734970601122;6694.598324100472;6762.966185169043;16465;16479.83520164807;16484.670306594042;16495.505134845975;16509.91745041267;7572.81067973294;7735.922640773632;7739.1873853245725;7740.961295193488;7741.380689544823;6245.812625621239;6254.937450625435;7283.016792733019;7306.671199796022;7367.079115110611;5208.479152084837;5303.7348132593215;5320.76056577455;5328.49379308965;5348.491893270133;1500.197394393994;1527.8472152784855;1527.938882444712;1536.8078990126235;1551.5500504853692;866.7139843851514;885.840548701227;899.5996781432331;901.0493928904937;901.5221932375852;8674.175953284428;8796.428232165017;8899.421537600036;9235.492047937429;9242.537873106452;985.3841349156777;986.9457179855177;989.569537251298;990.4552496127101;1001.0940099210227;5032.547070763576;5259.289995850588;5274.393444753826;5344.599155063386;5432.728363581805;19060.33288834912;20617.381478555668;20619.58760824775;20706.8611226381;20719.171233150806;19370.70943935842;20144.49638759043;20157.395278141386;20163;20222.898885505478;10976.341419514694;11168.21822472419;11333.489992950337;11343.376114313627;11354.091672666167;8239.711610093618;8261.710840120568;8346.707865224687;8370.958145209235;8479.2368686816;17275.7408638868;17388.91305543464;17602.647947041245;17753.73369399486;18031.368902088365;17818.643627127127;18244.817551824843;18337.541561461072;18435.170417330984;18598.814011859708;9790.461524616214;10188.184945204513;10376.16990640762;10602.310849795047;10620.628278010232;13883.861161388257;14015.929920350332;14035.50875719345;14039.227842468763;14043.08719933201;10731.356118632906;10789.56841726322;10818.459077045958;11302.660920172408;11466.713332166762;10713.946430267799;10943.726406839893;10969.396683182502;11154.051905588116;11439.313641181556;9833.557489913113;9945.602175913173;9961.35251208669;9969.750756231153;9970.252231082553;7026.613536255555;7036.155661320469;7042.119735033121;7053.4004609608755;7068.399186069241;7370.262973702801;7407.407407407287;7422.4433167513735;7454.105507338937;7512.849743005218 03:31:30 INFO - PID 2315 | |4;prototype.html;499.4331287384182;502.5350210299982;511.35058475735815;522.8823514709193;525.3643091858851;6255.186825712657;6293.811185664437;6298.244210694723;6314.210723659543;6356;5904.439078287566;6085.847853803657;6111.511079113671;6136.938630613704;6174.166487524183;4897.481780647992;4916.606671466274;4952.678075925053;4969.503049695019;5016.623753218523;4775.3075804008395;4851.539103785135;4869.756512174395;4971.428285747131;4977.129002424561;4858.368412106439;4895.898422854865;4916.704997700132;4940.654154209206;4941.8749937526845;4941.950580494221;4943.826966056171;4981.1283025470575;4996.875078123039;5000.274960130779;4400.471943366816;4424.9557504424865;4427.22523558376;4462.352958820953;4464.486584042844;6095.9695201524155;6134.141220229171;6136.110264011738;6142.385761423822;6158.261008678943;5723.971380143114;5732.799352022701;5741.650712082657;5747.856303592402;5755.453231942959;6436.903446448307;6463.482921366326;6537.346265373472;6561.93438065618;6573.80278591643;4820.542048505386;4834.226523756217;4838.201696720037;4883.3651625288485;4898.926516102261;6780.423663988567;6934.965325173342;6969.1985421676145;6970.895436568456;7025.92974070263;4891.266310053449;4957.355543779286;4958.206686930074;4973.875653108629;5003.624728145403;5051.722155281494;5073.594112470993;5094.337736094284;5094.974525127351;5098.566621837112;5702.287214098238;5731.168980497804;5733.197352370671;5754.5971781975695;5760.127958413533;6542.24764152119;6584.76953306632;6592.967035164794;6604.801855944329;6624.635645039569;1663.3180396037808;1702.7275635898197;1702.8978261304362;1717.2873257597957;1747.9038652874215;1195.9820602690966;1204.3616883051996;1226.5093962414926;1226.6565361698738;1231.2797013747058;5659.292588426447;5719.4852463277675;5723.255976723;5750.482456578844;5772.884542309131;6574.276829548746;6701.765438209639;6723.630200339028;6724.890393085184;6740.595564266159;5757.740901659436;5761.942380576224;5796.362400135981;5796.42035796417;5804.477597016289;3597.1007248187952;3614.8373323200517;3621.94567081494;3626.655467730561;3626.8730594429067;6496.317886621837;6519.152510173647;6567.967160164264;6577;6611.438027767597;6766.594004359754;6872.484563657646;6911.412529935012;6919.827004324932;7020.087388639444;6575.835604109936;6577.835554111186;6589.670516474253;6629.900551491732;6640.701168447335;6686.231083425469;6693.899591506034;6719.966400168163;6749.223839258548;6776.119104516381;6628.5028622852515;6671.432928201158;6691.130153080068;6708.6310252936555;6745.561538499982;6488.805335839932;6538.444232240123;6563.606183628997;6612.140421745143;6620.172478440195;4916.631252656137;4934.629902757236;4959.70241785494;4974.104661160881;4992.350994370854;6478.5141114415665;6760.1549806274215;6778.4577233822165;6822.079019332326;6830.419414349637;6282.277538082996;6371.426571608392;6439.871202576016;6450.871212499957;6456.9354306456335;6445.581037232565;6450.903236451551;6499.350064993463;6501.51238657112;6517.250516190698;6310.463610593151;6371.203599550056;6381;6441.484681225397;6471.06169605414;6342.714577843916;6469.385408386196;6482.837929051623;6524.934750652433;6581.3418658133805;6342.460890824332;6363.5227357947415;6371.840703982438;6427.935720642734;6430.678466076771 03:31:30 INFO - PID 2315 | |5;yui.html;892.8348255572718;898.5911410308306;916.2074805293419;923.6628630549851;934.3599634250539;18926.432207033795;19850.20599176033;19881.40355789325;20052.899735501338;22411.21560745374;21644.89177554114;22180.112795488203;22266.332010039685;22341.44146396341;22351.441213969658;962.2302158273388;979.9902000980006;984.8128855517429;985.2118305355687;986.8075725233582;990.6681261777313;1003.5534187286623;1005.4218824176091;1023.0485648347035;1033.0650761061213;1069.8341757027633;1071.196602548089;1071.2287153249647;1071.549949021409;1072.2654981337769;1066.3015724700333;1073.3398959639844;1074.3607553505651;1077.2297807067905;1077.7036315013434;781.0900301149154;783.6473586885924;784.051297929506;785.4070177016373;787.4527203593505;18453.538661533436;18625;18630.44108676742;18672;19517.512062198417;21640.242591509377;21862.672059919114;22058.779412205833;22058.779412205993;22073.779262207332;17101.144942752937;17129.571760705956;17243.74134387985;17276.913615431844;17499.475015749547;3647.8723244686576;3676.7977761223124;3679.632036796326;3680.2823449427374;3680.926381472382;125667;126208;128743;129632.35183824114;130221;16995.830041699424;17133.82866171322;17276.13619319054;17371.73942390865;17450.563735906704;31086.379915782934;31681.841590791897;31736;31924.521132183036;32371;7751.961240193763;8069.919300807034;8454;8770.605322760492;9168.129027742354;4324.308110702273;4463.732176069446;4466.821327146943;4467.463904331436;4467.642588592905;5439.619226654097;5466.262054622575;5487.670739755547;5528.75120619573;5569.777208911679;1066.983995240072;1092.1044743310408;1096.5449338524422;1102.4432661506048;1108.0194028284864;676.7090151234918;687.1273482676972;690.5787469643514;699.5697645947705;700.439648281383;12015.15893887437;12188.631200539374;12263.877361226452;12748.872511274954;13492.5277615283;700.2717174138941;706.5654622407183;709.868674295257;710.8187412209853;714.424887965193;5707.971460142756;6427.967860160669;6599.208095028627;6732.764353247613;6741.494387920828;107745.46127269314;108810;110056;111406.44296778626;115597.42201289108;95916.52041739746;97957;99038.5048074755;99542;102486;85194.57402712946;86024.5698771502;87186.69219961706;87589.56205218933;90751;13001.544945927226;13451.125676830974;13486.325683715579;13500.594982150551;13602.183868967497;39369.60630393659;40032.599674002886;40215.396769047904;40242;41030.1793964125;49620.50379496159;50998.74500627473;51452;51539.742301289756;51877.48122518878;19143.138558765168;19304.806951930863;19310.806891931465;19425.8057419424;19638.90180549088;20567.074481648662;21003.789962100796;21019.57960840805;21055.157793688082;21083.683744744147;18946.43160705181;19195.232190712217;19301.613967720285;19313.4205973821;19349;19340.516487087374;19740.40778776639;19814.801851981294;20352.69470957907;20717;19522.21911123539;19894.90052549728;20012.199512019357;20085.09617067265;20305.49236269105;13833;14616.049956752777;14966.850331496546;15136.470223542123;15162.3176957035;14803.333849976561;15138.697226055638;15155.166465844484;15549.455769048029;15622.296996634952 03:31:30 INFO - PID 2315 | __end_tp_report 03:31:30 INFO - PID 2315 | __start_cc_report 03:31:30 INFO - PID 2315 | _x_x_mozilla_cycle_collect,380 03:31:30 INFO - PID 2315 | __end_cc_report 03:31:30 INFO - PID 2315 | __startTimestamp1502879490473__endTimestamp 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | ------- Summary: start ------- 03:31:30 INFO - PID 2315 | Number of tests: 6 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | [#0] dojo.html Cycles:165 Average:6186.20 Median:6439.45 stddev:737.34 (11.5%) stddev-sans-first:699.71 03:31:30 INFO - PID 2315 | Values: 3136.4 3179.1 3187.6 3195.2 3221.5 6194.3 6196.1 6222.6 6232.8 6270.6 6220.6 6225.0 6338.0 6351.2 6367.6 5750.6 5787.4 5797.6 5851.4 5870.1 5626.2 5651.7 5762.1 5772.6 5772.8 5666.0 5686.5 5741.7 5802.3 5803.0 5710.3 5715.8 5777.8 5785.2 5787.5 5512.5 5667.4 5743.1 5748.4 5765.8 6080.4 6096.7 6109.8 6141.7 6155.1 6761.7 6788.4 6802.7 6808.5 6822.4 6770.1 6910.2 6922.1 6923.5 6926.7 6196.3 6230.8 6261.9 6330.3 6416.4 6726.3 6874.5 6899.2 6921.4 6921.8 6452.9 6527.7 6536.0 6545.0 6563.4 6781.6 6801.5 6803.8 6805.7 6809.3 6763.7 6774.6 6774.9 6816.8 6839.5 6676.1 6756.8 6765.8 6776.5 6783.1 5437.0 5454.3 5492.3 5521.2 5527.2 4566.7 4624.8 4653.7 4672.7 4692.2 5832.0 5836.1 5849.5 5852.9 5870.5 6701.5 6782.2 6805.9 6807.5 6814.8 5781.1 5801.3 5844.4 5864.5 5894.2 5809.5 5866.8 5898.2 5902.6 5962.7 6466.8 6475.0 6528.6 6530.5 6532.8 6620.0 6646.0 6690.1 6700.0 6714.0 5642.3 6431.5 6439.5 6476.2 6655.9 6626.1 6626.6 6654.4 6724.3 6764.9 6672.6 6803.8 6808.0 6810.3 6857.6 6517.9 6531.5 6539.9 6540.8 6540.9 6171.0 6535.5 6539.6 6540.2 6550.2 5922.3 6436.6 6463.8 6488.3 6508.1 6575.3 6629.7 6634.6 6663.4 6667.5 6372.9 6379.3 6462.4 6469.0 6477.3 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | [#1] ext.html Cycles:180 Average:38634.96 Median:36516.63 stddev:55121.45 (150.9%) stddev-sans-first:55202.90 03:31:30 INFO - PID 2315 | Values: 807.3 845.2 861.1 888.6 892.4 41426.0 42767.1 42771.1 42787.4 42843.6 37868.4 38167.4 38189.4 38239.4 38331.4 6731.5 6840.0 6849.9 6851.0 6853.9 6747.8 6835.6 6856.7 6860.7 6868.2 7388.9 7568.7 7576.7 7580.9 7581.4 7440.1 7527.2 7528.2 7542.6 7547.0 7293.2 7328.6 7484.7 7535.2 7536.6 38580.8 38619.8 38745.4 38838.6 38986.2 3458.7 3473.5 3504.1 3505.8 3509.4 30346.4 30432.4 30615.4 30685.4 30717.5 4943.5 4970.4 4981.0 4998.0 5010.9 180628.0 182378.0 186599.0 187749.0 195698.0 48936.3 49142.0 49200.8 49219.3 49275.0 49815.8 49824.5 50006.2 50139.0 50270.7 15289.8 15395.3 15413.9 15742.0 15887.6 11942.9 11950.2 11964.3 11972.9 11988.6 14370.6 14680.5 14776.9 14869.6 15029.5 5860.9 5939.5 5972.7 5985.5 6109.0 990.6 993.9 1025.9 1027.0 1027.7 23095.9 29078.7 29080.6 29873.1 30080.8 1087.7 1104.4 1107.5 1112.6 1118.4 12545.0 12799.6 12908.7 13065.3 13126.7 233035.0 234469.0 234827.0 235368.0 235376.0 54189.7 54516.2 54655.5 54666.0 54681.7 1420.9 1499.9 1516.7 1518.3 1522.1 23641.0 23794.3 23877.6 23908.9 24050.5 39838.8 40192.4 40224.0 40257.4 40301.2 209419.0 210582.0 214712.0 217085.0 218209.0 33920.8 34708.1 34824.1 37637.4 37693.1 45790.0 46792.8 47002.0 47024.1 47389.3 34352.7 34457.7 34730.8 34907.9 35044.3 33463.3 34206.1 34665.7 34700.8 34780.0 36960.3 37071.6 37390.3 37512.6 37812.4 32705.7 33252.3 34422.3 35553.9 36073.0 25426.1 25842.0 26320.9 26362.0 26502.7 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | [#2] jquery.html Cycles:180 Average:34698.58 Median:23354.88 stddev:77789.30 (333.1%) stddev-sans-first:77965.19 03:31:30 INFO - PID 2315 | Values: 519.4 537.1 553.5 565.7 574.3 21459.7 22111.3 22146.4 22161.4 22174.0 24604.0 25807.6 26786.2 27003.0 27035.6 6434.4 6608.4 6610.2 6612.7 6615.6 5945.7 6447.9 6484.3 6503.7 6505.0 6434.8 6474.6 6474.9 6479.8 6482.4 6409.2 6410.5 6414.4 6439.5 6452.7 6013.3 6032.5 6040.1 6040.9 6041.9 28165.0 29277.0 29621.4 29846.3 29857.9 1927.0 1982.7 1982.7 1983.5 1985.9 18948.6 19250.9 19326.3 19326.5 19518.3 2383.6 2427.6 2431.2 2440.6 2456.6 135368.0 143283.0 143307.0 143584.0 143855.0 14768.9 14977.6 14980.3 15235.5 15655.4 23339.0 23370.8 23423.3 23438.8 23504.8 10246.9 10384.5 10398.2 10455.3 10630.0 9621.5 9704.1 9768.2 9846.9 9890.8 8967.8 9291.8 9322.9 9701.7 9849.3 2557.8 2618.3 2698.0 2709.5 2768.2 940.9 978.9 986.3 990.6 997.8 19340.8 19394.9 19857.3 20289.4 20374.7 1036.9 1041.3 1046.0 1051.3 1052.9 10948.6 11144.6 11180.8 11220.2 11339.9 426774.0 452375.0 459941.0 466925.0 469473.0 118368.0 121442.4 123063.4 127834.0 128719.4 1219.8 1248.6 1301.9 1311.7 1311.9 20933.1 21059.0 21214.0 21245.3 21380.8 58998.7 60404.0 61971.0 62373.7 62678.7 76178.0 78275.2 79972.6 80330.2 81956.0 11474.7 13192.2 14611.4 15087.2 15107.6 23883.8 23923.6 24050.6 24125.5 24275.9 18504.2 18559.0 18701.2 18834.4 18880.5 16869.9 17134.4 17399.7 17746.6 17813.4 19029.5 19031.9 19882.5 19945.0 19999.6 14669.9 14886.9 15024.7 15046.6 15055.1 18379.8 18971.0 19216.4 19364.3 19460.0 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | [#3] mootools.html Cycles:180 Average:9032.57 Median:2649.05 stddev:6210.97 (234.5%) stddev-sans-first:6201.84 03:31:30 INFO - PID 2315 | Values: 1388.3 1442.1 1450.6 1451.3 1452.2 14132.9 14760.0 14765.6 14778.5 14787.0 14263.3 15284.2 15296.3 15314.4 15329.2 2740.7 2785.2 2790.7 2794.1 2794.3 2717.1 2745.9 2808.4 2814.3 2825.4 2516.4 2713.9 2774.8 2779.5 2789.1 2594.3 2598.5 2621.8 2653.3 2666.0 2642.1 2644.8 2660.1 2662.7 2663.4 13977.3 14356.6 14385.1 14390.5 14416.5 7625.5 7893.4 7894.0 7896.0 7897.4 4954.0 5093.1 5196.9 5215.7 5216.1 1935.6 1936.9 1939.5 1959.7 1990.8 23781.0 23918.0 23940.0 23950.6 23959.4 6490.3 6557.4 6625.7 6694.6 6763.0 16465.0 16479.8 16484.7 16495.5 16509.9 7572.8 7735.9 7739.2 7741.0 7741.4 6245.8 6254.9 7283.0 7306.7 7367.1 5208.5 5303.7 5320.8 5328.5 5348.5 1500.2 1527.8 1527.9 1536.8 1551.6 866.7 885.8 899.6 901.0 901.5 8674.2 8796.4 8899.4 9235.5 9242.5 985.4 986.9 989.6 990.5 1001.1 5032.5 5259.3 5274.4 5344.6 5432.7 19060.3 20617.4 20619.6 20706.9 20719.2 19370.7 20144.5 20157.4 20163.0 20222.9 10976.3 11168.2 11333.5 11343.4 11354.1 8239.7 8261.7 8346.7 8371.0 8479.2 17275.7 17388.9 17602.6 17753.7 18031.4 17818.6 18244.8 18337.5 18435.2 18598.8 9790.5 10188.2 10376.2 10602.3 10620.6 13883.9 14015.9 14035.5 14039.2 14043.1 10731.4 10789.6 10818.5 11302.7 11466.7 10713.9 10943.7 10969.4 11154.1 11439.3 9833.6 9945.6 9961.4 9969.8 9970.3 7026.6 7036.2 7042.1 7053.4 7068.4 7370.3 7407.4 7422.4 7454.1 7512.8 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | [#4] prototype.html Cycles:180 Average:5505.97 Median:6136.52 stddev:1553.23 (25.3%) stddev-sans-first:1511.45 03:31:30 INFO - PID 2315 | Values: 499.4 502.5 511.4 522.9 525.4 6255.2 6293.8 6298.2 6314.2 6356.0 5904.4 6085.8 6111.5 6136.9 6174.2 4897.5 4916.6 4952.7 4969.5 5016.6 4775.3 4851.5 4869.8 4971.4 4977.1 4858.4 4895.9 4916.7 4940.7 4941.9 4942.0 4943.8 4981.1 4996.9 5000.3 4400.5 4425.0 4427.2 4462.4 4464.5 6096.0 6134.1 6136.1 6142.4 6158.3 5724.0 5732.8 5741.7 5747.9 5755.5 6436.9 6463.5 6537.3 6561.9 6573.8 4820.5 4834.2 4838.2 4883.4 4898.9 6780.4 6935.0 6969.2 6970.9 7025.9 4891.3 4957.4 4958.2 4973.9 5003.6 5051.7 5073.6 5094.3 5095.0 5098.6 5702.3 5731.2 5733.2 5754.6 5760.1 6542.2 6584.8 6593.0 6604.8 6624.6 1663.3 1702.7 1702.9 1717.3 1747.9 1196.0 1204.4 1226.5 1226.7 1231.3 5659.3 5719.5 5723.3 5750.5 5772.9 6574.3 6701.8 6723.6 6724.9 6740.6 5757.7 5761.9 5796.4 5796.4 5804.5 3597.1 3614.8 3621.9 3626.7 3626.9 6496.3 6519.2 6568.0 6577.0 6611.4 6766.6 6872.5 6911.4 6919.8 7020.1 6575.8 6577.8 6589.7 6629.9 6640.7 6686.2 6693.9 6720.0 6749.2 6776.1 6628.5 6671.4 6691.1 6708.6 6745.6 6488.8 6538.4 6563.6 6612.1 6620.2 4916.6 4934.6 4959.7 4974.1 4992.4 6478.5 6760.2 6778.5 6822.1 6830.4 6282.3 6371.4 6439.9 6450.9 6456.9 6445.6 6450.9 6499.4 6501.5 6517.3 6310.5 6371.2 6381.0 6441.5 6471.1 6342.7 6469.4 6482.8 6524.9 6581.3 6342.5 6363.5 6371.8 6427.9 6430.7 03:31:30 INFO - PID 2315 | 03:31:30 INFO - PID 2315 | [#5] yui.html Cycles:180 Average:23834.49 Median:21011.68 stddev:31791.92 (151.3%) stddev-sans-first:31834.43 03:31:30 INFO - PID 2315 | Values: 892.8 898.6 916.2 923.7 934.4 18926.4 19850.2 19881.4 20052.9 22411.2 21644.9 22180.1 22266.3 22341.4 22351.4 962.2 980.0 984.8 985.2 986.8 990.7 1003.6 1005.4 1023.0 1033.1 1069.8 1071.2 1071.2 1071.5 1072.3 1066.3 1073.3 1074.4 1077.2 1077.7 781.1 783.6 784.1 785.4 787.5 18453.5 18625.0 18630.4 18672.0 19517.5 21640.2 21862.7 22058.8 22058.8 22073.8 17101.1 17129.6 17243.7 17276.9 17499.5 3647.9 3676.8 3679.6 3680.3 3680.9 125667.0 126208.0 128743.0 129632.4 130221.0 16995.8 17133.8 17276.1 17371.7 17450.6 31086.4 31681.8 31736.0 31924.5 32371.0 7752.0 8069.9 8454.0 8770.6 9168.1 4324.3 4463.7 4466.8 4467.5 4467.6 5439.6 5466.3 5487.7 5528.8 5569.8 1067.0 1092.1 1096.5 1102.4 1108.0 676.7 687.1 690.6 699.6 700.4 12015.2 12188.6 12263.9 12748.9 13492.5 700.3 706.6 709.9 710.8 714.4 5708.0 6428.0 6599.2 6732.8 6741.5 107745.5 108810.0 110056.0 111406.4 115597.4 95916.5 97957.0 99038.5 99542.0 102486.0 85194.6 86024.6 87186.7 87589.6 90751.0 13001.5 13451.1 13486.3 13500.6 13602.2 39369.6 40032.6 40215.4 40242.0 41030.2 49620.5 50998.7 51452.0 51539.7 51877.5 19143.1 19304.8 19310.8 19425.8 19638.9 20567.1 21003.8 21019.6 21055.2 21083.7 18946.4 19195.2 19301.6 19313.4 19349.0 19340.5 19740.4 19814.8 20352.7 20717.0 19522.2 19894.9 20012.2 20085.1 20305.5 13833.0 14616.0 14966.9 15136.5 15162.3 14803.3 15138.7 15155.2 15549.5 15622.3 03:31:30 INFO - PID 2315 | -------- Summary: end -------- 03:31:30 INFO - PID 2315 | 03:31:31 INFO - TEST-INFO | 2315: exit 0 03:31:31 INFO - TEST-OK | dromaeo_css | took 1113266ms 03:31:31 INFO - TEST-START | kraken 03:31:31 INFO - Initialising browser for kraken test... 03:31:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55325/getInfo.html -profile /tmp/tmpoHFsmg/profile 03:31:31 INFO - TEST-INFO | started process 2853 (/builds/slave/test/build/application/firefox/firefox http://localhost:55325/getInfo.html) 03:31:37 INFO - TEST-INFO | 2853: exit 0 03:31:37 INFO - Browser initialized. 03:31:37 INFO - Running cycle 1/1 for kraken test... 03:31:37 INFO - TEST-INFO | started process 3123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoHFsmg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:31:38 INFO - PID 3123 | 03:31:38 INFO - PID 3123 | (/builds/slave/test/build/application/firefox/firefox:3177): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:31:38 INFO - PID 3123 | 03:31:49 INFO - PID 3123 | RSS: Main: 167673856 03:31:49 INFO - PID 3123 | 03:31:51 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55325/tests/kraken/driver.html?testName=audio-beat-detection) 03:31:52 INFO - PID 3123 | RSS: Main: 173924352 03:31:52 INFO - PID 3123 | 03:31:55 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55325/tests/kraken/driver.html?testName=audio-dft) 03:31:56 INFO - PID 3123 | RSS: Main: 177790976 03:31:56 INFO - PID 3123 | 03:31:59 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55325/tests/kraken/driver.html?testName=audio-fft) 03:31:59 INFO - PID 3123 | RSS: Main: 172494848 03:31:59 INFO - PID 3123 | 03:32:01 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55325/tests/kraken/driver.html?testName=audio-oscillator) 03:32:02 INFO - PID 3123 | RSS: Main: 172277760 03:32:02 INFO - PID 3123 | 03:32:04 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55325/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:32:05 INFO - PID 3123 | RSS: Main: 172417024 03:32:05 INFO - PID 3123 | 03:32:08 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55325/tests/kraken/driver.html?testName=imaging-darkroom) 03:32:09 INFO - PID 3123 | RSS: Main: 173764608 03:32:09 INFO - PID 3123 | 03:32:12 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55325/tests/kraken/driver.html?testName=imaging-desaturate) 03:32:13 INFO - PID 3123 | RSS: Main: 173604864 03:32:13 INFO - PID 3123 | 03:32:16 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55325/tests/kraken/driver.html?testName=json-parse-financial) 03:32:16 INFO - PID 3123 | RSS: Main: 173789184 03:32:16 INFO - PID 3123 | 03:32:18 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55325/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:32:19 INFO - PID 3123 | RSS: Main: 174170112 03:32:19 INFO - PID 3123 | 03:32:20 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:32:21 INFO - PID 3123 | RSS: Main: 174194688 03:32:21 INFO - PID 3123 | 03:32:23 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:32:24 INFO - PID 3123 | RSS: Main: 174243840 03:32:24 INFO - PID 3123 | 03:32:27 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:32:28 INFO - PID 3123 | RSS: Main: 174743552 03:32:28 INFO - PID 3123 | 03:32:31 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:32:31 INFO - PID 3123 | RSS: Main: 174362624 03:32:31 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | Cycle 1(1): loaded http://localhost:55325/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55325/tests/kraken/driver.html?testName=ai-astar) 03:32:33 INFO - PID 3123 | RSS: Main: 176025600 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | __start_tp_report 03:32:33 INFO - PID 3123 | _x_x_mozilla_page_load 03:32:33 INFO - PID 3123 | _x_x_mozilla_page_load_details 03:32:33 INFO - PID 3123 | |i|pagename|runs| 03:32:33 INFO - PID 3123 | |0;ai-astar;100;107;103;114;107;113;111;101;108;108 03:32:33 INFO - PID 3123 | |1;audio-beat-detection;151;122;159;178;119;148;146;125;177;122 03:32:33 INFO - PID 3123 | |2;audio-dft;162;169;164;165;166;163;165;173;166;166 03:32:33 INFO - PID 3123 | |3;audio-fft;77;75;74;175;77;76;76;86;102;78 03:32:33 INFO - PID 3123 | |4;audio-oscillator;79;80;142;78;78;86;148;84;80;139 03:32:33 INFO - PID 3123 | |5;imaging-gaussian-blur;115;114;114;114;114;114;114;121;118;114 03:32:33 INFO - PID 3123 | |6;imaging-darkroom;151;157;150;150;150;150;153;153;151;150 03:32:33 INFO - PID 3123 | |7;imaging-desaturate;94;94;94;94;98;93;94;94;94;94 03:32:33 INFO - PID 3123 | |8;json-parse-financial;57;55;55;54;56;55;62;56;54;58 03:32:33 INFO - PID 3123 | |9;json-stringify-tinderbox;54;55;54;53;54;54;58;60;54;54 03:32:33 INFO - PID 3123 | |10;stanford-crypto-aes;77;76;77;78;78;84;77;84;78;77 03:32:33 INFO - PID 3123 | |11;stanford-crypto-ccm;124;136;126;128;148;134;125;135;132;171 03:32:33 INFO - PID 3123 | |12;stanford-crypto-pbkdf2;181;174;181;174;185;179;179;176;177;175 03:32:33 INFO - PID 3123 | |13;stanford-crypto-sha256-iterative;70;72;68;70;69;68;67;69;67;72 03:32:33 INFO - PID 3123 | __end_tp_report 03:32:33 INFO - PID 3123 | __start_cc_report 03:32:33 INFO - PID 3123 | _x_x_mozilla_cycle_collect,963 03:32:33 INFO - PID 3123 | __end_cc_report 03:32:33 INFO - PID 3123 | __startTimestamp1502879553850__endTimestamp 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | ------- Summary: start ------- 03:32:33 INFO - PID 3123 | Number of tests: 14 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#0] ai-astar Cycles:10 Average:107.20 Median:108.00 stddev:4.76 (4.4%) stddev-sans-first:4.27 03:32:33 INFO - PID 3123 | Values: 100.0 107.0 103.0 114.0 107.0 113.0 111.0 101.0 108.0 108.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:149.50 stddev:22.33 (14.9%) stddev-sans-first:23.57 03:32:33 INFO - PID 3123 | Values: 151.0 122.0 159.0 178.0 119.0 148.0 146.0 125.0 177.0 122.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#2] audio-dft Cycles:10 Average:165.90 Median:166.00 stddev:3.14 (1.9%) stddev-sans-first:3.00 03:32:33 INFO - PID 3123 | Values: 162.0 169.0 164.0 165.0 166.0 163.0 165.0 173.0 166.0 166.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#3] audio-fft Cycles:10 Average:89.60 Median:76.50 stddev:31.16 (40.7%) stddev-sans-first:32.71 03:32:33 INFO - PID 3123 | Values: 77.0 75.0 74.0 175.0 77.0 76.0 76.0 86.0 102.0 78.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#4] audio-oscillator Cycles:10 Average:99.40 Median:79.50 stddev:30.27 (38.1%) stddev-sans-first:31.19 03:32:33 INFO - PID 3123 | Values: 79.0 80.0 142.0 78.0 78.0 86.0 148.0 84.0 80.0 139.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:114.00 stddev:2.39 (2.1%) stddev-sans-first:2.54 03:32:33 INFO - PID 3123 | Values: 115.0 114.0 114.0 114.0 114.0 114.0 114.0 121.0 118.0 114.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:151.00 stddev:2.27 (1.5%) stddev-sans-first:2.40 03:32:33 INFO - PID 3123 | Values: 151.0 157.0 150.0 150.0 150.0 150.0 153.0 153.0 151.0 150.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.34 (1.4%) stddev-sans-first:1.41 03:32:33 INFO - PID 3123 | Values: 94.0 94.0 94.0 94.0 98.0 93.0 94.0 94.0 94.0 94.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:2.39 (4.3%) stddev-sans-first:2.52 03:32:33 INFO - PID 3123 | Values: 57.0 55.0 55.0 54.0 56.0 55.0 62.0 56.0 54.0 58.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#9] json-stringify-tinderbox Cycles:10 Average:55.00 Median:54.00 stddev:2.21 (4.1%) stddev-sans-first:2.32 03:32:33 INFO - PID 3123 | Values: 54.0 55.0 54.0 53.0 54.0 54.0 58.0 60.0 54.0 54.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#10] stanford-crypto-aes Cycles:10 Average:78.60 Median:78.00 stddev:2.91 (3.7%) stddev-sans-first:3.03 03:32:33 INFO - PID 3123 | Values: 77.0 76.0 77.0 78.0 78.0 84.0 77.0 84.0 78.0 77.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#11] stanford-crypto-ccm Cycles:10 Average:135.90 Median:134.50 stddev:14.22 (10.6%) stddev-sans-first:14.41 03:32:33 INFO - PID 3123 | Values: 124.0 136.0 126.0 128.0 148.0 134.0 125.0 135.0 132.0 171.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:179.00 stddev:3.57 (2.0%) stddev-sans-first:3.63 03:32:33 INFO - PID 3123 | Values: 181.0 174.0 181.0 174.0 185.0 179.0 179.0 176.0 177.0 175.0 03:32:33 INFO - PID 3123 | 03:32:33 INFO - PID 3123 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:1.81 (2.6%) stddev-sans-first:1.90 03:32:33 INFO - PID 3123 | Values: 70.0 72.0 68.0 70.0 69.0 68.0 67.0 69.0 67.0 72.0 03:32:33 INFO - PID 3123 | -------- Summary: end -------- 03:32:33 INFO - PID 3123 | 03:32:34 INFO - PID 3123 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:32:34 INFO - TEST-INFO | 3123: exit 0 03:32:34 INFO - TEST-OK | kraken | took 63275ms 03:32:34 INFO - SUITE-END | took 1176s 03:32:34 INFO - Completed test suite (00:19:36) 03:32:34 INFO - javascript benchmark 03:32:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8603.122355479689, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.357046805405, 3179.062176657886, 3187.5537424760537, 3195.217171792911, 3221.468457704479, 6194.318624951257, 6196.13254144419, 6222.595531290469, 6232.781852635158, 6270.623762574249, 6220.6267623942595, 6224.96887515563, 6337.968310158454, 6351.23785145782, 6367.554271201017, 5750.597458177908, 5787.421257874221, 5797.565182611311, 5851.385604511529, 5870.090136028901, 5626.212330273765, 5651.745671444774, 5762.135679648044, 5772.5670574706855, 5772.769089236425, 5665.971670141644, 5686.516646085088, 5741.655500669973, 5802.274715660543, 5803.0134877071, 5710.314762228559, 5715.799947001877, 5777.826665200051, 5785.2189954356045, 5787.536997040227, 5512.476314750093, 5667.376588575295, 5743.10981797822, 5748.367679555246, 5765.798197063083, 6080.361562035967, 6096.664683442409, 6109.816705498843, 6141.662208578527, 6155.138280640714, 6761.695723692445, 6788.355106264897, 6802.727890884359, 6808.455323574102, 6822.4200942919915, 6770.119884414995, 6910.2398736139485, 6922.06552115463, 6923.4807389446, 6926.688299026556, 6196.349383314732, 6230.813075607739, 6261.8747625047245, 6330.335314791972, 6416.358364163546, 6726.293739157416, 6874.518783685094, 6899.206591242071, 6921.411680007155, 6921.757738479229, 6452.935470645328, 6527.67361631926, 6535.967320163464, 6544.9672751635935, 6563.409293163638, 6781.62701051437, 6801.4898882584, 6803.795886123424, 6805.727770889109, 6809.285025072395, 6763.6618169092335, 6774.627395493295, 6774.898376524356, 6816.7614133505085, 6839.521233513607, 6676.098726671934, 6756.797296081126, 6765.830854228684, 6776.457883369319, 6783.050372947791, 5437.02133615953, 5454.290942177491, 5492.340919089655, 5521.199426083195, 5527.198556209327, 4566.748828814447, 4624.815007399667, 4653.697509661861, 4672.672912896064, 4692.178868698033, 5831.9708401458565, 5836.06622940336, 5849.532037436995, 5852.853678658068, 5870.471657550841, 6701.4921642630015, 6782.2200446948245, 6805.863882722318, 6807.4894382921475, 6814.773340798604, 5781.132830075522, 5801.3328467225965, 5844.357120716718, 5864.472197502245, 5894.17481552583, 5809.535237181015, 5866.794662186803, 5898.174255604219, 5902.61632993854, 5962.672053037038, 6466.838329041811, 6474.996375561701, 6528.575642583217, 6530.542861999709, 6532.836679082966, 6619.966900165661, 6646.0, 6690.13028306317, 6700.0, 6714.026466162281, 5642.266505354278, 6431.453326467303, 6439.45264652491, 6476.190476190476, 6655.900161497679, 6626.138601981712, 6626.56927299724, 6654.401103900672, 6724.260331363448, 6764.864702706017, 6672.599644021374, 6803.829904252433, 6808.046873437624, 6810.284920083518, 6857.588544687334, 6517.869642607026, 6531.477481801349, 6539.901901471577, 6540.803775886731, 6540.934590654033, 6170.969145154246, 6535.509836762167, 6539.607623542603, 6540.215174179129, 6550.181227346582, 5922.2597175353085, 6436.613803171825, 6463.838404039937, 6488.318726533835, 6508.056331831764, 6575.309592492719, 6629.701663425061, 6634.568753031038, 6663.36698013688, 6667.49993750461, 6372.9362706374195, 6379.330170332234, 6462.4183823456115, 6469.0, 6477.255115661759], "value": 6131.122080104883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.2734538914975, 845.2350622686463, 861.0614430271005, 888.6178943054485, 892.4466830565051, 41426.0, 42767.14465710684, 42771.14457710844, 42787.35818962714, 42843.57156428435, 37868.43197352035, 38167.427488587695, 38189.42715859264, 38239.4264086039, 38331.425028624595, 6731.461483081354, 6840.0, 6849.897251541206, 6851.0, 6853.931460685404, 6747.831304217385, 6835.589864608114, 6856.657167141648, 6860.656967151647, 6868.175818901739, 7388.852222955537, 7568.697252109909, 7576.734814281501, 7580.886286705704, 7581.3934885209055, 7440.069991251094, 7527.20964298749, 7528.1719010908755, 7542.58515781629, 7546.962265188639, 7293.234210407883, 7328.633568321616, 7484.6631901564015, 7535.171131175566, 7536.585487798169, 38580.80709596462, 38619.8414047579, 38745.41881871774, 38838.61161388378, 38986.22027559461, 3458.6887180153653, 3473.478978153272, 3504.106452854531, 3505.7545971781988, 3509.4034014217646, 30346.393072138653, 30432.391352173054, 30615.38769224625, 30685.386292274252, 30717.539236911463, 4943.456219815817, 4970.428400733889, 4981.0, 4998.0, 5010.874728131789, 180628.0, 182378.0, 186599.0, 187749.0, 195698.0, 48936.26595601069, 49142.0, 49200.75399623051, 49219.26171107436, 49275.0, 49815.75092124589, 49824.50175498199, 50006.24990625143, 50139.0, 50270.748646256536, 15289.847101528841, 15395.307211175525, 15413.9229303855, 15742.0, 15887.602809929613, 11942.940285298519, 11950.163488555718, 11964.341961192218, 11972.940135299443, 11988.580399685969, 14370.640733981734, 14680.486182983757, 14776.852231477547, 14869.553913382615, 15029.549113526611, 5860.8534786629825, 5939.46544810969, 5972.731227094791, 5985.491233245136, 6109.0, 990.6131416017633, 993.9155171810332, 1025.8512515685184, 1027.024326889443, 1027.7482016905756, 23095.88452057729, 29078.709212908023, 29080.563791543143, 29873.10380688583, 30080.849595751883, 1087.7443800706828, 1104.3705088099866, 1107.534835369147, 1112.5883423133491, 1118.373710721982, 12544.996400288137, 12799.552015679406, 12908.741825163632, 13065.281409522377, 13126.671833204246, 233035.0, 234469.0, 234827.0, 235368.0, 235376.0, 54189.72905135528, 54516.18225726617, 54655.453445465835, 54666.0, 54681.72659136679, 1420.850810664886, 1499.9400023999135, 1516.651170230853, 1518.2560545332708, 1522.0867479512203, 23641.0, 23794.286171414886, 23877.641835372135, 23908.880455598308, 24050.51898962046, 39838.80080599579, 40192.39711404273, 40224.0, 40257.39613905735, 40301.19397611973, 209419.0, 210582.0, 214712.0, 217085.0, 218209.0, 33920.830395847865, 34708.13229669177, 34824.12939676528, 37637.435438468994, 37693.05767355838, 45790.0, 46792.766036170964, 47002.0, 47024.059518810114, 47389.28916066193, 34352.656473434945, 34457.655423445445, 34730.82634586912, 34907.93511617663, 35044.29911401809, 33463.33073338569, 34206.14484637904, 34665.65334346624, 34700.82649586736, 34780.0, 36960.26079478342, 37071.62928370682, 37390.25219495649, 37512.62487375201, 37812.43281350837, 32705.672943271216, 33252.33495330128, 34422.31155376929, 35553.933381998584, 36073.0, 25426.110086146895, 25842.0, 26320.9471621133, 26362.0, 26502.734972650025], "value": 17398.474590780406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.4441947116585, 537.0628253697299, 553.4603761332698, 565.7454145634464, 574.3280361976489, 21459.67810482842, 22111.336659900207, 22146.44633884154, 22161.44596385091, 22173.999999999978, 24604.0, 25807.61288580668, 26786.19641410761, 27003.0, 27035.594466082974, 6434.420902118808, 6608.405243528082, 6610.2067751869845, 6612.735490580371, 6615.603063816162, 5945.672987985659, 6447.935520644804, 6484.319146489622, 6503.674816259192, 6505.024246363036, 6434.839129021765, 6474.643894585796, 6474.870502589945, 6479.773207937723, 6482.416582507573, 6409.230892292908, 6410.519211059166, 6414.390632889868, 6439.549231553793, 6452.709628066725, 6013.338532761392, 6032.5173986081445, 6040.12418199363, 6040.879182416371, 6041.909371359389, 28165.0, 29277.0, 29621.407571848442, 29846.253843653863, 29857.850710746523, 1926.951826204342, 1982.7025946108056, 1982.7224188613607, 1983.5338695406567, 1985.8907760073191, 18948.62102757937, 19250.903745481184, 19326.323578674677, 19326.516837079045, 19518.31685890987, 2383.642453631952, 2427.599446091393, 2431.234161239204, 2440.6217036359394, 2456.6192240202795, 135368.0, 143282.99999999895, 143307.0, 143584.0, 143855.0, 14768.926155369369, 14977.625559360886, 14980.250987450583, 15235.466758663393, 15655.37378504847, 23339.0, 23370.7662923372, 23423.297301080995, 23438.765612344, 23504.7649523506, 10246.948765256275, 10384.480775961172, 10398.168146548258, 10455.320404173704, 10630.0, 9621.51892405377, 9704.126628603459, 9768.21854251658, 9846.901530984598, 9890.752731181778, 8967.820643587092, 9291.76770580741, 9322.906770932339, 9701.660441884642, 9849.310548261696, 2557.7825884799627, 2618.3192369983562, 2697.986510067437, 2709.539378305693, 2768.179911721811, 940.8682784410188, 978.9265805064649, 986.3490096536252, 990.6186118344489, 997.800439912006, 19340.806591934183, 19394.903025484782, 19857.304994325128, 20289.391318260477, 20374.694379584318, 1036.8807587127426, 1041.271110222847, 1045.9895401046044, 1051.2588625019496, 1052.8683914510686, 10948.56205751761, 11144.60993865211, 11180.776384472265, 11220.158488113424, 11339.943300283447, 426774.0, 452375.0, 459941.0, 466925.0, 469473.0, 118368.0, 121442.39278803549, 123063.38468307602, 127834.0, 128719.35640321739, 1219.804831227017, 1248.6004478566772, 1301.8502872169822, 1311.7179806341874, 1311.895048396126, 20933.058012389174, 21059.0, 21214.0, 21245.256416025364, 21380.78619213788, 58998.705006474695, 60404.0, 61971.0, 62373.68813155905, 62678.6866065685, 76178.0, 78275.21724782679, 79972.60013699894, 80330.19669803228, 81956.0, 11474.655760327203, 13192.20846749198, 14611.415543378145, 15087.170205638356, 15107.564781345754, 23883.761162388848, 23923.641145382484, 24050.639240411758, 24125.51748964976, 24275.878620606785, 18504.1673124707, 18559.0, 18701.158447869606, 18834.434966951012, 18880.52798679989, 16869.91565042167, 17134.40029599008, 17399.652006960045, 17746.64506709833, 17813.376531821323, 19029.524261893563, 19031.90484047626, 19882.50293742668, 19945.0, 19999.600007999466, 14669.92665036668, 14886.851131488547, 15024.6995060096, 15046.623834404227, 15055.096694198382, 18379.81620183781, 18971.0, 19216.423507294803, 19364.322248721226, 19460.0], "value": 12175.636638582118, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.278095390397, 1442.0554536778407, 1450.572081236035, 1451.3323871019331, 1452.2012892908897, 14132.858671413283, 14760.0, 14765.557033289018, 14778.482753103644, 14787.0, 14263.286835658202, 15284.159371234606, 15296.31166597503, 15314.387424503006, 15329.156896370696, 2740.7122252163535, 2785.220138361252, 2790.6511686039244, 2794.147784925597, 2794.273488892892, 2717.076194094008, 2745.862706864659, 2808.4383123375305, 2814.2542226310043, 2825.4490374377006, 2516.421223118684, 2713.905013324534, 2774.7641450476735, 2779.4580056888913, 2789.065663002886, 2594.2995391244353, 2598.4803039392104, 2621.7640412362884, 2653.2968763277745, 2666.013574977251, 2642.101685426964, 2644.801639877017, 2660.0955675070377, 2662.720414356484, 2663.427363116939, 13977.301134943315, 14356.569302920929, 14385.064970776866, 14390.496332628307, 14416.495422660157, 7625.466217364787, 7893.447458677895, 7893.960530197312, 7896.0, 7897.407694422884, 4954.007689300775, 5093.1087059764395, 5196.948030519684, 5215.713135777531, 5216.139337009389, 1935.5935253596622, 1936.9225230990744, 1939.5345117171778, 1959.6962470817045, 1990.8108729670657, 23781.0, 23918.0, 23940.0, 23950.64074038891, 23959.401014974766, 6490.2536208335705, 6557.409833115043, 6625.734970601122, 6694.598324100472, 6762.966185169043, 16465.0, 16479.83520164807, 16484.670306594042, 16495.505134845975, 16509.91745041267, 7572.81067973294, 7735.922640773632, 7739.1873853245725, 7740.961295193488, 7741.380689544823, 6245.812625621239, 6254.937450625435, 7283.016792733019, 7306.671199796022, 7367.079115110611, 5208.479152084837, 5303.7348132593215, 5320.76056577455, 5328.49379308965, 5348.491893270133, 1500.197394393994, 1527.8472152784855, 1527.938882444712, 1536.8078990126235, 1551.5500504853692, 866.7139843851514, 885.840548701227, 899.5996781432331, 901.0493928904937, 901.5221932375852, 8674.175953284428, 8796.428232165017, 8899.421537600036, 9235.492047937429, 9242.537873106452, 985.3841349156777, 986.9457179855177, 989.569537251298, 990.4552496127101, 1001.0940099210227, 5032.547070763576, 5259.289995850588, 5274.393444753826, 5344.599155063386, 5432.728363581805, 19060.33288834912, 20617.381478555668, 20619.58760824775, 20706.8611226381, 20719.171233150806, 19370.70943935842, 20144.49638759043, 20157.395278141386, 20163.0, 20222.898885505478, 10976.341419514694, 11168.21822472419, 11333.489992950337, 11343.376114313627, 11354.091672666167, 8239.711610093618, 8261.710840120568, 8346.707865224687, 8370.958145209235, 8479.2368686816, 17275.7408638868, 17388.91305543464, 17602.647947041245, 17753.73369399486, 18031.368902088365, 17818.643627127127, 18244.817551824843, 18337.541561461072, 18435.170417330984, 18598.814011859708, 9790.461524616214, 10188.184945204513, 10376.16990640762, 10602.310849795047, 10620.628278010232, 13883.861161388257, 14015.929920350332, 14035.50875719345, 14039.227842468763, 14043.08719933201, 10731.356118632906, 10789.56841726322, 10818.459077045958, 11302.660920172408, 11466.713332166762, 10713.946430267799, 10943.726406839893, 10969.396683182502, 11154.051905588116, 11439.313641181556, 9833.557489913113, 9945.602175913173, 9961.35251208669, 9969.750756231153, 9970.252231082553, 7026.613536255555, 7036.155661320469, 7042.119735033121, 7053.4004609608755, 7068.399186069241, 7370.262973702801, 7407.407407407287, 7422.4433167513735, 7454.105507338937, 7512.849743005218], "value": 6574.097388015651, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.4331287384182, 502.5350210299982, 511.35058475735815, 522.8823514709193, 525.3643091858851, 6255.186825712657, 6293.811185664437, 6298.244210694723, 6314.210723659543, 6356.0, 5904.439078287566, 6085.847853803657, 6111.511079113671, 6136.938630613704, 6174.166487524183, 4897.481780647992, 4916.606671466274, 4952.678075925053, 4969.503049695019, 5016.623753218523, 4775.3075804008395, 4851.539103785135, 4869.756512174395, 4971.428285747131, 4977.129002424561, 4858.368412106439, 4895.898422854865, 4916.704997700132, 4940.654154209206, 4941.8749937526845, 4941.950580494221, 4943.826966056171, 4981.1283025470575, 4996.875078123039, 5000.274960130779, 4400.471943366816, 4424.9557504424865, 4427.22523558376, 4462.352958820953, 4464.486584042844, 6095.9695201524155, 6134.141220229171, 6136.110264011738, 6142.385761423822, 6158.261008678943, 5723.971380143114, 5732.799352022701, 5741.650712082657, 5747.856303592402, 5755.453231942959, 6436.903446448307, 6463.482921366326, 6537.346265373472, 6561.93438065618, 6573.80278591643, 4820.542048505386, 4834.226523756217, 4838.201696720037, 4883.3651625288485, 4898.926516102261, 6780.423663988567, 6934.965325173342, 6969.1985421676145, 6970.895436568456, 7025.92974070263, 4891.266310053449, 4957.355543779286, 4958.206686930074, 4973.875653108629, 5003.624728145403, 5051.722155281494, 5073.594112470993, 5094.337736094284, 5094.974525127351, 5098.566621837112, 5702.287214098238, 5731.168980497804, 5733.197352370671, 5754.5971781975695, 5760.127958413533, 6542.24764152119, 6584.76953306632, 6592.967035164794, 6604.801855944329, 6624.635645039569, 1663.3180396037808, 1702.7275635898197, 1702.8978261304362, 1717.2873257597957, 1747.9038652874215, 1195.9820602690966, 1204.3616883051996, 1226.5093962414926, 1226.6565361698738, 1231.2797013747058, 5659.292588426447, 5719.4852463277675, 5723.255976723, 5750.482456578844, 5772.884542309131, 6574.276829548746, 6701.765438209639, 6723.630200339028, 6724.890393085184, 6740.595564266159, 5757.740901659436, 5761.942380576224, 5796.362400135981, 5796.42035796417, 5804.477597016289, 3597.1007248187952, 3614.8373323200517, 3621.94567081494, 3626.655467730561, 3626.8730594429067, 6496.317886621837, 6519.152510173647, 6567.967160164264, 6577.0, 6611.438027767597, 6766.594004359754, 6872.484563657646, 6911.412529935012, 6919.827004324932, 7020.087388639444, 6575.835604109936, 6577.835554111186, 6589.670516474253, 6629.900551491732, 6640.701168447335, 6686.231083425469, 6693.899591506034, 6719.966400168163, 6749.223839258548, 6776.119104516381, 6628.5028622852515, 6671.432928201158, 6691.130153080068, 6708.6310252936555, 6745.561538499982, 6488.805335839932, 6538.444232240123, 6563.606183628997, 6612.140421745143, 6620.172478440195, 4916.631252656137, 4934.629902757236, 4959.70241785494, 4974.104661160881, 4992.350994370854, 6478.5141114415665, 6760.1549806274215, 6778.4577233822165, 6822.079019332326, 6830.419414349637, 6282.277538082996, 6371.426571608392, 6439.871202576016, 6450.871212499957, 6456.9354306456335, 6445.581037232565, 6450.903236451551, 6499.350064993463, 6501.51238657112, 6517.250516190698, 6310.463610593151, 6371.203599550056, 6381.0, 6441.484681225397, 6471.06169605414, 6342.714577843916, 6469.385408386196, 6482.837929051623, 6524.934750652433, 6581.3418658133805, 6342.460890824332, 6363.5227357947415, 6371.840703982438, 6427.935720642734, 6430.678466076771], "value": 5047.293206875072, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.8348255572718, 898.5911410308306, 916.2074805293419, 923.6628630549851, 934.3599634250539, 18926.432207033795, 19850.20599176033, 19881.40355789325, 20052.899735501338, 22411.21560745374, 21644.89177554114, 22180.112795488203, 22266.332010039685, 22341.44146396341, 22351.441213969658, 962.2302158273388, 979.9902000980006, 984.8128855517429, 985.2118305355687, 986.8075725233582, 990.6681261777313, 1003.5534187286623, 1005.4218824176091, 1023.0485648347035, 1033.0650761061213, 1069.8341757027633, 1071.196602548089, 1071.2287153249647, 1071.549949021409, 1072.2654981337769, 1066.3015724700333, 1073.3398959639844, 1074.3607553505651, 1077.2297807067905, 1077.7036315013434, 781.0900301149154, 783.6473586885924, 784.051297929506, 785.4070177016373, 787.4527203593505, 18453.538661533436, 18625.0, 18630.44108676742, 18672.0, 19517.512062198417, 21640.242591509377, 21862.672059919114, 22058.779412205833, 22058.779412205993, 22073.779262207332, 17101.144942752937, 17129.571760705956, 17243.74134387985, 17276.913615431844, 17499.475015749547, 3647.8723244686576, 3676.7977761223124, 3679.632036796326, 3680.2823449427374, 3680.926381472382, 125667.0, 126208.0, 128743.0, 129632.35183824114, 130221.0, 16995.830041699424, 17133.82866171322, 17276.13619319054, 17371.73942390865, 17450.563735906704, 31086.379915782934, 31681.841590791897, 31736.0, 31924.521132183036, 32371.0, 7751.961240193763, 8069.919300807034, 8454.0, 8770.605322760492, 9168.129027742354, 4324.308110702273, 4463.732176069446, 4466.821327146943, 4467.463904331436, 4467.642588592905, 5439.619226654097, 5466.262054622575, 5487.670739755547, 5528.75120619573, 5569.777208911679, 1066.983995240072, 1092.1044743310408, 1096.5449338524422, 1102.4432661506048, 1108.0194028284864, 676.7090151234918, 687.1273482676972, 690.5787469643514, 699.5697645947705, 700.439648281383, 12015.15893887437, 12188.631200539374, 12263.877361226452, 12748.872511274954, 13492.5277615283, 700.2717174138941, 706.5654622407183, 709.868674295257, 710.8187412209853, 714.424887965193, 5707.971460142756, 6427.967860160669, 6599.208095028627, 6732.764353247613, 6741.494387920828, 107745.46127269314, 108810.0, 110056.0, 111406.44296778626, 115597.42201289108, 95916.52041739746, 97957.0, 99038.5048074755, 99542.0, 102486.0, 85194.57402712946, 86024.5698771502, 87186.69219961706, 87589.56205218933, 90751.0, 13001.544945927226, 13451.125676830974, 13486.325683715579, 13500.594982150551, 13602.183868967497, 39369.60630393659, 40032.599674002886, 40215.396769047904, 40242.0, 41030.1793964125, 49620.50379496159, 50998.74500627473, 51452.0, 51539.742301289756, 51877.48122518878, 19143.138558765168, 19304.806951930863, 19310.806891931465, 19425.8057419424, 19638.90180549088, 20567.074481648662, 21003.789962100796, 21019.57960840805, 21055.157793688082, 21083.683744744147, 18946.43160705181, 19195.232190712217, 19301.613967720285, 19313.4205973821, 19349.0, 19340.516487087374, 19740.40778776639, 19814.801851981294, 20352.69470957907, 20717.0, 19522.21911123539, 19894.90052549728, 20012.199512019357, 20085.09617067265, 20305.49236269105, 13833.0, 14616.049956752777, 14966.850331496546, 15136.470223542123, 15162.3176957035, 14803.333849976561, 15138.697226055638, 15155.166465844484, 15549.455769048029, 15622.296996634952], "value": 9407.45760394094, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 103.0, 114.0, 107.0, 113.0, 111.0, 101.0, 108.0, 108.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 159.0, 178.0, 119.0, 148.0, 146.0, 125.0, 177.0, 122.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 169.0, 164.0, 165.0, 166.0, 163.0, 165.0, 173.0, 166.0, 166.0], "value": 165.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 175.0, 77.0, 76.0, 76.0, 86.0, 102.0, 78.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 142.0, 78.0, 78.0, 86.0, 148.0, 84.0, 80.0, 139.0], "value": 99.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 121.0, 118.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 157.0, 150.0, 150.0, 150.0, 150.0, 153.0, 153.0, 151.0, 150.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 98.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 54.0, 56.0, 55.0, 62.0, 56.0, 54.0, 58.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 58.0, 60.0, 54.0, 54.0], "value": 55.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 78.0, 78.0, 84.0, 77.0, 84.0, 78.0, 77.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 136.0, 126.0, 128.0, 148.0, 134.0, 125.0, 135.0, 132.0, 171.0], "value": 135.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 174.0, 181.0, 174.0, 185.0, 179.0, 179.0, 176.0, 177.0, 175.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 68.0, 70.0, 69.0, 68.0, 67.0, 69.0, 67.0, 72.0], "value": 69.2, "unit": "score"}]}]} 03:32:35 INFO - Return code: 0 03:32:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:32:35 INFO - # TBPL SUCCESS # 03:32:35 INFO - Running post-action listener: _package_coverage_data 03:32:35 INFO - Running post-action listener: _resource_record_post_action 03:32:35 INFO - [mozharness: 2017-08-16 10:32:35.109816Z] Finished run-tests step (success) 03:32:35 INFO - Running post-run listener: _resource_record_post_run 03:32:35 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 428740608; Read time: 28; Write time: 556848 03:32:35 INFO - TinderboxPrint: CPU usage
13.2% 03:32:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 28 03:32:35 INFO - TinderboxPrint: I/O write bytes / time
428,740,608 / 556,848 03:32:35 INFO - TinderboxPrint: CPU idle
8,272.7 (86.8%) 03:32:35 INFO - TinderboxPrint: CPU user
1,201.9 (12.6%) 03:32:35 INFO - TinderboxPrint: Swap in / out
0 / 0 03:32:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106627072; Read time: 0; Write time: 131780 03:32:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:32:35 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 320991232; Read time: 0; Write time: 424972 03:32:35 INFO - Running post-run listener: _upload_blobber_files 03:32:35 INFO - Blob upload gear active. 03:32:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:32:35 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 03:32:35 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'] 03:32:35 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 03:32:36 INFO - (blobuploader) - INFO - Open directory for files ... 03:32:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:32:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:32:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:32:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:32:37 INFO - (blobuploader) - INFO - Done attempting. 03:32:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:32:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:32:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:32:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:32:38 INFO - (blobuploader) - INFO - Done attempting. 03:32:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:32:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:32:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:32:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:32:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:32:38 INFO - (blobuploader) - INFO - Done attempting. 03:32:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:32:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:32:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:32:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:32:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:32:45 INFO - (blobuploader) - INFO - Done attempting. 03:32:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:32:45 INFO - Return code: 0 03:32:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:32:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:32:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9fc6898102747e80f2f5057508e37732a7e4f7733d554da2e82c5fb332225c0eb985f90caecd814668ae2db559f3a5cf74256d51be8ab6e72c7ef55d4a34915", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc94c6e6896dd521260cfab3569d00ec465bdc1e8e16b8de82ed266245e7978ebecea6c2ac46548dc4dcc608ebd920564e7bee7f567b7e00e013c0f84dd39e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ce3c9c2f734578a82e17fa28ff3268c0f7cb3278643c52d1a9c6c63ea3cdbd9379ffa72fd219044ebbfa74f74bd8a21735052a785cf22694e3a453298d998f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7192b1ecc4ec6cfe087e85a8cac0c9196d20193fffa39badf840bb0d9b9eb0b9334e65215fff7f6f68db51b965e8a29a6acb62c2fca8d38e3dedeb45f8d85e63"} 03:32:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:32:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:32:45 INFO - Contents: 03:32:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9fc6898102747e80f2f5057508e37732a7e4f7733d554da2e82c5fb332225c0eb985f90caecd814668ae2db559f3a5cf74256d51be8ab6e72c7ef55d4a34915", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc94c6e6896dd521260cfab3569d00ec465bdc1e8e16b8de82ed266245e7978ebecea6c2ac46548dc4dcc608ebd920564e7bee7f567b7e00e013c0f84dd39e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ce3c9c2f734578a82e17fa28ff3268c0f7cb3278643c52d1a9c6c63ea3cdbd9379ffa72fd219044ebbfa74f74bd8a21735052a785cf22694e3a453298d998f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7192b1ecc4ec6cfe087e85a8cac0c9196d20193fffa39badf840bb0d9b9eb0b9334e65215fff7f6f68db51b965e8a29a6acb62c2fca8d38e3dedeb45f8d85e63"} 03:32:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:32:45 INFO - Copying logs to upload dir... 03:32:45 INFO - mkdir: /builds/slave/test/build/upload/logs 03:32:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.236701 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-16 03:32:45.119635) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 03:32:45.128043) ========= 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=ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978 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/c9fc6898102747e80f2f5057508e37732a7e4f7733d554da2e82c5fb332225c0eb985f90caecd814668ae2db559f3a5cf74256d51be8ab6e72c7ef55d4a34915", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc94c6e6896dd521260cfab3569d00ec465bdc1e8e16b8de82ed266245e7978ebecea6c2ac46548dc4dcc608ebd920564e7bee7f567b7e00e013c0f84dd39e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ce3c9c2f734578a82e17fa28ff3268c0f7cb3278643c52d1a9c6c63ea3cdbd9379ffa72fd219044ebbfa74f74bd8a21735052a785cf22694e3a453298d998f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7192b1ecc4ec6cfe087e85a8cac0c9196d20193fffa39badf840bb0d9b9eb0b9334e65215fff7f6f68db51b965e8a29a6acb62c2fca8d38e3dedeb45f8d85e63"} build_url:https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/HuMrrxyuS1iW8gGOnr-3Zg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9fc6898102747e80f2f5057508e37732a7e4f7733d554da2e82c5fb332225c0eb985f90caecd814668ae2db559f3a5cf74256d51be8ab6e72c7ef55d4a34915", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc94c6e6896dd521260cfab3569d00ec465bdc1e8e16b8de82ed266245e7978ebecea6c2ac46548dc4dcc608ebd920564e7bee7f567b7e00e013c0f84dd39e76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16ce3c9c2f734578a82e17fa28ff3268c0f7cb3278643c52d1a9c6c63ea3cdbd9379ffa72fd219044ebbfa74f74bd8a21735052a785cf22694e3a453298d998f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7192b1ecc4ec6cfe087e85a8cac0c9196d20193fffa39badf840bb0d9b9eb0b9334e65215fff7f6f68db51b965e8a29a6acb62c2fca8d38e3dedeb45f8d85e63"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 03:32:45.154373) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:32:45.159784) ========= 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=ba164b0a7b0ec7655860e956000001c1-1502878306.217037-1634882978 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 03:32:45.239588) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 03:32:45.239931) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 03:32:45.240235) ========= ========= Total master_lag: 0.20 =========