builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-051 starttime: 1502193842.85 results: success (0) revision: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:02.847335) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:02.847845) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:02.848140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:02.868504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:02.868880) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 05:04:03-- 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% 92.9M=0s 2017-08-08 05:04:03 (92.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.000706) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.001042) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019890 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.061360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.061689) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a921bfb8a2cf3db4d9edebe9b35799a3f9d035da --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 a921bfb8a2cf3db4d9edebe9b35799a3f9d035da --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=5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 05:04:03,411 truncating revision to first 12 chars 2017-08-08 05:04:03,411 Setting DEBUG logging. 2017-08-08 05:04:03,411 attempt 1/10 2017-08-08 05:04:03,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a921bfb8a2cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 05:04:03,710 unpacking tar archive at: mozilla-central-a921bfb8a2cf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704737 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.788773) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.793167) ========= script_repo_revision: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.793591) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.793858) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:03.806018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-08 05:04:03.806370) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:04:04 INFO - MultiFileLogger online at 20170808 05:04:04 in /builds/slave/test 05:04:04 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 05:04:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:04 INFO - {'append_to_log': False, 05:04:04 INFO - 'base_work_dir': '/builds/slave/test', 05:04:04 INFO - 'blob_upload_branch': 'Firefox', 05:04:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:04 INFO - 'branch': 'Firefox', 05:04:04 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:04 INFO - 'code_coverage': False, 05:04:04 INFO - 'config_files': ('talos/linux_config.py',), 05:04:04 INFO - 'default_actions': ('clobber', 05:04:04 INFO - 'read-buildbot-config', 05:04:04 INFO - 'download-and-extract', 05:04:04 INFO - 'populate-webroot', 05:04:04 INFO - 'create-virtualenv', 05:04:04 INFO - 'install', 05:04:04 INFO - 'setup-mitmproxy', 05:04:04 INFO - 'run-tests'), 05:04:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:04 INFO - 'disable_ccov_upload': False, 05:04:04 INFO - 'download_minidump_stackwalk': True, 05:04:04 INFO - 'download_symbols': 'ondemand', 05:04:04 INFO - 'enable_stylo': False, 05:04:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:04 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:04 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:04 INFO - 'gecko_profile': False, 05:04:04 INFO - 'gecko_profile_interval': 0, 05:04:04 INFO - 'installer_path': 'installer.exe', 05:04:04 INFO - 'log_level': 'info', 05:04:04 INFO - 'log_name': 'talos', 05:04:04 INFO - 'log_to_console': True, 05:04:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:04:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:04:04 INFO - 'opt_config_files': (), 05:04:04 INFO - 'pip_index': False, 05:04:04 INFO - 'suite': 'dromaeojs-e10s', 05:04:04 INFO - 'system_bits': '32', 05:04:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:04 INFO - 'title': 'talos-linux64-ix-051', 05:04:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:04 INFO - 'use_talos_json': True, 05:04:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:04 INFO - 'work_dir': 'build'} 05:04:04 INFO - [mozharness: 2017-08-08 12:04:04.257072Z] Running clobber step. 05:04:04 INFO - Running pre-action listener: _resource_record_pre_action 05:04:04 INFO - Running main action method: clobber 05:04:04 INFO - rmtree: /builds/slave/test/build 05:04:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:05 INFO - Running post-action listener: _resource_record_post_action 05:04:05 INFO - [mozharness: 2017-08-08 12:04:05.813959Z] Finished clobber step (success) 05:04:05 INFO - [mozharness: 2017-08-08 12:04:05.814061Z] Running read-buildbot-config step. 05:04:05 INFO - Running pre-action listener: _resource_record_pre_action 05:04:05 INFO - Running main action method: read_buildbot_config 05:04:05 INFO - Using buildbot properties: 05:04:05 INFO - { 05:04:05 INFO - "project": "", 05:04:05 INFO - "product": "firefox", 05:04:05 INFO - "who": "cbook@mozilla.com", 05:04:05 INFO - "installer_path": "public/build/target.tar.bz2", 05:04:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 05:04:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:04:05 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 05:04:05 INFO - "stage_platform": "linux64-stylosequential", 05:04:05 INFO - "basedir": "/builds/slave/test", 05:04:05 INFO - "buildnumber": 22, 05:04:05 INFO - "platform": "ubuntu64_hw_styloseq", 05:04:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:04:05 INFO - "slavebuilddir": "test", 05:04:05 INFO - "taskId": "EAd5lsozSJu-xTWMXEY-Vw", 05:04:05 INFO - "branch": "mozilla-central", 05:04:05 INFO - "script_repo_revision": "production", 05:04:05 INFO - "revision": "a921bfb8a2cf3db4d9edebe9b35799a3f9d035da", 05:04:05 INFO - "slavename": "talos-linux64-ix-051", 05:04:05 INFO - "repo_path": "mozilla-central" 05:04:05 INFO - } 05:04:05 INFO - Finding installer, test and symbols from parent task. 05:04:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAd5lsozSJu-xTWMXEY-Vw'}, attempt #1 05:04:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EAd5lsozSJu-xTWMXEY-Vw'}, attempt #1 05:04:06 INFO - Task dependencies: BYO0e8EGR6OPzlcMpTM4Pw 05:04:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw'}, attempt #1 05:04:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json 05:04:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip 05:04:07 INFO - Running post-action listener: _resource_record_post_action 05:04:07 INFO - [mozharness: 2017-08-08 12:04:07.333076Z] Finished read-buildbot-config step (success) 05:04:07 INFO - [mozharness: 2017-08-08 12:04:07.333299Z] Running download-and-extract step. 05:04:07 INFO - Running pre-action listener: _resource_record_pre_action 05:04:07 INFO - Running main action method: download_and_extract 05:04:07 INFO - mkdir: /builds/slave/test/build/tests 05:04:07 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')]} 05:04:07 INFO - trying https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json 05:04:07 INFO - Downloading https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:04:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:04:07 INFO - Downloaded 989 bytes. 05:04:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:04:07 INFO - Using the following test package requirements: 05:04:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:04:07 INFO - u'common': [u'target.common.tests.zip'], 05:04:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:04:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:04:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:04:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:04:07 INFO - u'mozbase': [u'target.common.tests.zip'], 05:04:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:04:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:04:07 INFO - u'web-platform': [u'target.common.tests.zip', 05:04:07 INFO - u'target.web-platform.tests.tar.gz'], 05:04:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:04:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:04:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip 05:04:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:04:07 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip into memory 05:04:08 INFO - Content-Length response header: 54284738 05:04:09 INFO - Bytes received: 54284738 05:04:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:04:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip 05:04:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:04:20 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip into memory 05:04:21 INFO - Content-Length response header: 54284738 05:04:21 INFO - Bytes received: 54284738 05:04:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip 05:04:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:04:33 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip into memory 05:04:33 INFO - Content-Length response header: 14496008 05:04:33 INFO - Bytes received: 14496008 05:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:34 INFO - trying https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:34 INFO - Downloading https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:04:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:04:35 INFO - Downloaded 61893500 bytes. 05:04:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:35 INFO - mkdir: /builds/slave/test/properties 05:04:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:35 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:35 INFO - Contents: 05:04:35 INFO - build_url:https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:35 INFO - Running post-action listener: _resource_record_post_action 05:04:35 INFO - Running post-action listener: set_extra_try_arguments 05:04:35 INFO - [mozharness: 2017-08-08 12:04:35.698015Z] Finished download-and-extract step (success) 05:04:35 INFO - [mozharness: 2017-08-08 12:04:35.698129Z] Running populate-webroot step. 05:04:35 INFO - Running pre-action listener: _resource_record_pre_action 05:04:35 INFO - Running main action method: populate_webroot 05:04:35 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:04:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:04:35 INFO - 'tests': ['damp', 'tps']}, 05:04:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:04:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:04:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:04:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:35 INFO - 'ts_paint', 05:04:35 INFO - 'tpaint', 05:04:35 INFO - 'sessionrestore', 05:04:35 INFO - 'sessionrestore_many_windows', 05:04:35 INFO - 'sessionrestore_no_auto_restore', 05:04:35 INFO - 'tabpaint']}, 05:04:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:04:35 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:04:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:35 INFO - 'tsvgr_opacity', 05:04:35 INFO - 'tart', 05:04:35 INFO - 'tscrollx', 05:04:35 INFO - 'tsvg_static']}, 05:04:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:04:35 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:35 INFO - 'talos_options': ['--mitmproxy', 05:04:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:35 INFO - '--firstNonBlankPaint'], 05:04:35 INFO - 'tests': ['tp6_google', 05:04:35 INFO - 'tp6_youtube', 05:04:35 INFO - 'tp6_amazon', 05:04:35 INFO - 'tp6_facebook']}, 05:04:35 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:35 INFO - 'talos_options': ['--stylo', 05:04:35 INFO - '--mitmproxy', 05:04:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:35 INFO - '--firstNonBlankPaint'], 05:04:35 INFO - 'tests': ['tp6_google', 05:04:35 INFO - 'tp6_youtube', 05:04:35 INFO - 'tp6_amazon', 05:04:35 INFO - 'tp6_facebook']}, 05:04:35 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:35 INFO - 'talos_options': ['--stylo', 05:04:35 INFO - '--stylo-threads=1', 05:04:35 INFO - '--mitmproxy', 05:04:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:35 INFO - '--firstNonBlankPaint'], 05:04:35 INFO - 'tests': ['tp6_google', 05:04:35 INFO - 'tp6_youtube', 05:04:35 INFO - 'tp6_amazon', 05:04:35 INFO - 'tp6_facebook']}, 05:04:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:04:35 INFO - 'talos_options': ['--xperf_path', 05:04:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:35 INFO - 'tests': ['tp5n']}}} 05:04:35 INFO - Running post-action listener: _resource_record_post_action 05:04:35 INFO - [mozharness: 2017-08-08 12:04:35.707674Z] Finished populate-webroot step (success) 05:04:35 INFO - [mozharness: 2017-08-08 12:04:35.707765Z] Running create-virtualenv step. 05:04:35 INFO - Running pre-action listener: _resource_record_pre_action 05:04:35 INFO - Running main action method: create_virtualenv 05:04:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:35 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 05:04:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:04:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:35 INFO - Using real prefix '/usr' 05:04:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:38 INFO - Installing pip.................done. 05:04:38 INFO - Return code: 0 05:04:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:38 INFO - Reading from file tmpfile_stdout 05:04:38 INFO - Output received: 05:04:38 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) 05:04:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:38 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:38 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 05:04:38 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 05:04:38 INFO - Using env: {'DISPLAY': ':0', 05:04:38 INFO - 'HOME': '/home/cltbld', 05:04:38 INFO - 'LANG': 'en_US.UTF-8', 05:04:38 INFO - 'LANGUAGE': 'en_US:en', 05:04:38 INFO - 'LOGNAME': 'cltbld', 05:04:38 INFO - 'MAIL': '/var/mail/cltbld', 05:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:38 INFO - 'MOZ_NO_REMOTE': '1', 05:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:38 INFO - 'NO_EM_RESTART': '1', 05:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:38 INFO - 'PWD': '/builds/slave/test', 05:04:38 INFO - 'SHELL': '/bin/bash', 05:04:38 INFO - 'SHLVL': '1', 05:04:38 INFO - 'TERM': 'linux', 05:04:38 INFO - 'TMOUT': '86400', 05:04:38 INFO - 'USER': 'cltbld', 05:04:38 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:38 INFO - '_': '/tools/buildbot/bin/python'} 05:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:38 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 05:04:38 INFO - Cleaning up... 05:04:38 INFO - Return code: 0 05:04:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:38 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:38 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 05:04:38 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 05:04:38 INFO - Using env: {'DISPLAY': ':0', 05:04:38 INFO - 'HOME': '/home/cltbld', 05:04:38 INFO - 'LANG': 'en_US.UTF-8', 05:04:38 INFO - 'LANGUAGE': 'en_US:en', 05:04:38 INFO - 'LOGNAME': 'cltbld', 05:04:38 INFO - 'MAIL': '/var/mail/cltbld', 05:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:38 INFO - 'MOZ_NO_REMOTE': '1', 05:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:38 INFO - 'NO_EM_RESTART': '1', 05:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:38 INFO - 'PWD': '/builds/slave/test', 05:04:38 INFO - 'SHELL': '/bin/bash', 05:04:38 INFO - 'SHLVL': '1', 05:04:38 INFO - 'TERM': 'linux', 05:04:38 INFO - 'TMOUT': '86400', 05:04:38 INFO - 'USER': 'cltbld', 05:04:38 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:38 INFO - '_': '/tools/buildbot/bin/python'} 05:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:38 INFO - Downloading/unpacking psutil>=3.1.1 05:04:38 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 05:04:38 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 05:04:38 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 05:04:38 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 05:04:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:40 INFO - Installing collected packages: psutil 05:04:40 INFO - Running setup.py install for psutil 05:04:40 INFO - building 'psutil._psutil_linux' extension 05:04:40 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 05:04:40 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 05:04:40 INFO - building 'psutil._psutil_posix' extension 05:04:40 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 05:04:40 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 05:04:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:40 INFO - Successfully installed psutil 05:04:40 INFO - Cleaning up... 05:04:40 INFO - Return code: 0 05:04:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:40 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:40 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 05:04:40 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 05:04:40 INFO - Using env: {'DISPLAY': ':0', 05:04:40 INFO - 'HOME': '/home/cltbld', 05:04:40 INFO - 'LANG': 'en_US.UTF-8', 05:04:40 INFO - 'LANGUAGE': 'en_US:en', 05:04:40 INFO - 'LOGNAME': 'cltbld', 05:04:40 INFO - 'MAIL': '/var/mail/cltbld', 05:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:40 INFO - 'MOZ_NO_REMOTE': '1', 05:04:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:40 INFO - 'NO_EM_RESTART': '1', 05:04:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:40 INFO - 'PWD': '/builds/slave/test', 05:04:40 INFO - 'SHELL': '/bin/bash', 05:04:40 INFO - 'SHLVL': '1', 05:04:40 INFO - 'TERM': 'linux', 05:04:40 INFO - 'TMOUT': '86400', 05:04:40 INFO - 'USER': 'cltbld', 05:04:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:40 INFO - '_': '/tools/buildbot/bin/python'} 05:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:42 INFO - Installing collected packages: mozsystemmonitor 05:04:42 INFO - Running setup.py install for mozsystemmonitor 05:04:42 INFO - Successfully installed mozsystemmonitor 05:04:42 INFO - Cleaning up... 05:04:42 INFO - Return code: 0 05:04:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04:42 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')]} 05:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:42 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')]} 05:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:42 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 05:04:42 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 05:04:42 INFO - Using env: {'DISPLAY': ':0', 05:04:42 INFO - 'HOME': '/home/cltbld', 05:04:42 INFO - 'LANG': 'en_US.UTF-8', 05:04:42 INFO - 'LANGUAGE': 'en_US:en', 05:04:42 INFO - 'LOGNAME': 'cltbld', 05:04:42 INFO - 'MAIL': '/var/mail/cltbld', 05:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:42 INFO - 'MOZ_NO_REMOTE': '1', 05:04:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:42 INFO - 'NO_EM_RESTART': '1', 05:04:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:42 INFO - 'PWD': '/builds/slave/test', 05:04:42 INFO - 'SHELL': '/bin/bash', 05:04:42 INFO - 'SHLVL': '1', 05:04:42 INFO - 'TERM': 'linux', 05:04:42 INFO - 'TMOUT': '86400', 05:04:42 INFO - 'USER': 'cltbld', 05:04:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:42 INFO - '_': '/tools/buildbot/bin/python'} 05:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:42 INFO - Downloading/unpacking jsonschema==2.5.1 05:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:44 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:44 INFO - warning: no files found matching '*.txt' 05:04:44 INFO - no previously-included directories found matching 'build' 05:04:44 INFO - no previously-included directories found matching 'dist' 05:04:44 INFO - no previously-included directories found matching '.git*' 05:04:44 INFO - Installing collected packages: jsonschema, functools32 05:04:44 INFO - Running setup.py install for functools32 05:04:44 INFO - warning: no files found matching '*.txt' 05:04:44 INFO - no previously-included directories found matching 'build' 05:04:44 INFO - no previously-included directories found matching 'dist' 05:04:44 INFO - no previously-included directories found matching '.git*' 05:04:44 INFO - Successfully installed jsonschema functools32 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:44 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:44 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 05:04:44 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 05:04:44 INFO - Using env: {'DISPLAY': ':0', 05:04:44 INFO - 'HOME': '/home/cltbld', 05:04:44 INFO - 'LANG': 'en_US.UTF-8', 05:04:44 INFO - 'LANGUAGE': 'en_US:en', 05:04:44 INFO - 'LOGNAME': 'cltbld', 05:04:44 INFO - 'MAIL': '/var/mail/cltbld', 05:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:44 INFO - 'MOZ_NO_REMOTE': '1', 05:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:44 INFO - 'NO_EM_RESTART': '1', 05:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:44 INFO - 'PWD': '/builds/slave/test', 05:04:44 INFO - 'SHELL': '/bin/bash', 05:04:44 INFO - 'SHLVL': '1', 05:04:44 INFO - 'TERM': 'linux', 05:04:44 INFO - 'TMOUT': '86400', 05:04:44 INFO - 'USER': 'cltbld', 05:04:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:44 INFO - '_': '/tools/buildbot/bin/python'} 05:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:04:44 INFO - Cleaning up... 05:04:44 INFO - Return code: 0 05:04:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:44 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:44 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 05:04:44 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 05:04:44 INFO - Using env: {'DISPLAY': ':0', 05:04:44 INFO - 'HOME': '/home/cltbld', 05:04:44 INFO - 'LANG': 'en_US.UTF-8', 05:04:44 INFO - 'LANGUAGE': 'en_US:en', 05:04:44 INFO - 'LOGNAME': 'cltbld', 05:04:44 INFO - 'MAIL': '/var/mail/cltbld', 05:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:44 INFO - 'MOZ_NO_REMOTE': '1', 05:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:44 INFO - 'NO_EM_RESTART': '1', 05:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:44 INFO - 'PWD': '/builds/slave/test', 05:04:44 INFO - 'SHELL': '/bin/bash', 05:04:44 INFO - 'SHLVL': '1', 05:04:44 INFO - 'TERM': 'linux', 05:04:44 INFO - 'TMOUT': '86400', 05:04:44 INFO - 'USER': 'cltbld', 05:04:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:44 INFO - '_': '/tools/buildbot/bin/python'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:47 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 05:04:47 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 05:04:47 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 05:04:47 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 05:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:47 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 05:04:47 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 05:04:47 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 05:04:47 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 05:04:47 INFO - Downloading docopt-0.6.1.tar.gz 05:04:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:47 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:47 INFO - Running setup.py install for blobuploader 05:04:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:47 INFO - Running setup.py install for requests 05:04:48 INFO - Running setup.py install for docopt 05:04:48 INFO - Successfully installed blobuploader requests docopt 05:04:48 INFO - Cleaning up... 05:04:48 INFO - Return code: 0 05:04:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:48 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')]} 05:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:48 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')]} 05:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:48 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:48 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 05:04:48 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 05:04:48 INFO - Using env: {'DISPLAY': ':0', 05:04:48 INFO - 'HOME': '/home/cltbld', 05:04:48 INFO - 'LANG': 'en_US.UTF-8', 05:04:48 INFO - 'LANGUAGE': 'en_US:en', 05:04:48 INFO - 'LOGNAME': 'cltbld', 05:04:48 INFO - 'MAIL': '/var/mail/cltbld', 05:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:48 INFO - 'MOZ_NO_REMOTE': '1', 05:04:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:48 INFO - 'NO_EM_RESTART': '1', 05:04:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:48 INFO - 'PWD': '/builds/slave/test', 05:04:48 INFO - 'SHELL': '/bin/bash', 05:04:48 INFO - 'SHLVL': '1', 05:04:48 INFO - 'TERM': 'linux', 05:04:48 INFO - 'TMOUT': '86400', 05:04:48 INFO - 'USER': 'cltbld', 05:04:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:48 INFO - '_': '/tools/buildbot/bin/python'} 05:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:48 INFO - Running setup.py (path:/tmp/pip-HSkIbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:48 INFO - Running setup.py (path:/tmp/pip-hsewBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:48 INFO - Running setup.py (path:/tmp/pip-FPxx3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:48 INFO - Running setup.py (path:/tmp/pip-kkiw_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:48 INFO - Running setup.py (path:/tmp/pip-06ygmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:49 INFO - Running setup.py (path:/tmp/pip-hqwTT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:49 INFO - Running setup.py (path:/tmp/pip-GWfeHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:49 INFO - Running setup.py (path:/tmp/pip-_8tG0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:49 INFO - Running setup.py (path:/tmp/pip-AmXe01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:49 INFO - Running setup.py (path:/tmp/pip-VcbKYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:49 INFO - Running setup.py (path:/tmp/pip-XgAMNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:49 INFO - Running setup.py (path:/tmp/pip-n98n4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:49 INFO - Running setup.py (path:/tmp/pip-c0lU1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:49 INFO - Running setup.py (path:/tmp/pip-QQrCHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:49 INFO - Running setup.py (path:/tmp/pip-dS1Ff7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:49 INFO - Running setup.py (path:/tmp/pip-VKNuhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:49 INFO - Running setup.py (path:/tmp/pip-jjVRN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:49 INFO - Running setup.py install for manifestparser 05:04:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:49 INFO - Running setup.py install for mozcrash 05:04:50 INFO - Running setup.py install for mozdebug 05:04:50 INFO - Running setup.py install for mozdevice 05:04:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Running setup.py install for mozfile 05:04:50 INFO - Running setup.py install for mozhttpd 05:04:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Running setup.py install for mozinfo 05:04:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Running setup.py install for mozInstall 05:04:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Running setup.py install for mozleak 05:04:50 INFO - Running setup.py install for mozlog 05:04:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Running setup.py install for moznetwork 05:04:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:50 INFO - Running setup.py install for mozprocess 05:04:51 INFO - Running setup.py install for mozprofile 05:04:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:51 INFO - Running setup.py install for mozrunner 05:04:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:51 INFO - Running setup.py install for mozscreenshot 05:04:51 INFO - Running setup.py install for moztest 05:04:51 INFO - Running setup.py install for mozversion 05:04:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:51 INFO - Cleaning up... 05:04:51 INFO - Return code: 0 05:04:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:51 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')]} 05:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:51 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')]} 05:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:51 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:51 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 05:04:51 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 05:04:51 INFO - Using env: {'DISPLAY': ':0', 05:04:51 INFO - 'HOME': '/home/cltbld', 05:04:51 INFO - 'LANG': 'en_US.UTF-8', 05:04:51 INFO - 'LANGUAGE': 'en_US:en', 05:04:51 INFO - 'LOGNAME': 'cltbld', 05:04:51 INFO - 'MAIL': '/var/mail/cltbld', 05:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:51 INFO - 'MOZ_NO_REMOTE': '1', 05:04:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:51 INFO - 'NO_EM_RESTART': '1', 05:04:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:51 INFO - 'PWD': '/builds/slave/test', 05:04:51 INFO - 'SHELL': '/bin/bash', 05:04:51 INFO - 'SHLVL': '1', 05:04:51 INFO - 'TERM': 'linux', 05:04:51 INFO - 'TMOUT': '86400', 05:04:51 INFO - 'USER': 'cltbld', 05:04:51 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:51 INFO - '_': '/tools/buildbot/bin/python'} 05:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:51 INFO - Running setup.py (path:/tmp/pip-Z2n4Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:51 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)) 05:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:51 INFO - Running setup.py (path:/tmp/pip-vLog0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:52 INFO - Running setup.py (path:/tmp/pip-aAZvZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:52 INFO - Running setup.py (path:/tmp/pip-P_QT96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:52 INFO - Running setup.py (path:/tmp/pip-Dydw6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:52 INFO - Running setup.py (path:/tmp/pip-B8PieH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:52 INFO - Running setup.py (path:/tmp/pip-kSabAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:52 INFO - Running setup.py (path:/tmp/pip-vpa97O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:52 INFO - Running setup.py (path:/tmp/pip-bFks7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:52 INFO - Running setup.py (path:/tmp/pip-gYHTBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:52 INFO - Running setup.py (path:/tmp/pip-JbDqLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:52 INFO - Running setup.py (path:/tmp/pip-dnGFK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:52 INFO - Running setup.py (path:/tmp/pip-YCtnWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:52 INFO - Running setup.py (path:/tmp/pip-7M20Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:52 INFO - Running setup.py (path:/tmp/pip-MAQpDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:52 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)) 05:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:52 INFO - Running setup.py (path:/tmp/pip-yTYydU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:53 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)) 05:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:53 INFO - Running setup.py (path:/tmp/pip-lgjlo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:53 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)) 05:04:53 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)) 05:04:53 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)) 05:04:53 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)) 05:04:53 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)) 05:04:53 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)) 05:04:53 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)) 05:04:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:53 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 05:04:53 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 05:04:53 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 05:04:53 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 05:04:54 INFO - Downloading blessings-1.6.tar.gz 05:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:54 INFO - Installing collected packages: blessings 05:04:54 INFO - Running setup.py install for blessings 05:04:54 INFO - Successfully installed blessings 05:04:54 INFO - Cleaning up... 05:04:54 INFO - Return code: 0 05:04:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:54 INFO - Reading from file tmpfile_stdout 05:04:54 INFO - Current package versions: 05:04:54 INFO - argparse == 1.2.1 05:04:54 INFO - blessings == 1.6 05:04:54 INFO - blobuploader == 1.2.4 05:04:54 INFO - docopt == 0.6.1 05:04:54 INFO - functools32 == 3.2.3-2 05:04:54 INFO - jsonschema == 2.5.1 05:04:54 INFO - manifestparser == 1.1 05:04:54 INFO - mozInstall == 1.13 05:04:54 INFO - mozcrash == 1.0 05:04:54 INFO - mozdebug == 0.1 05:04:54 INFO - mozdevice == 0.51 05:04:54 INFO - mozfile == 1.2 05:04:54 INFO - mozhttpd == 0.7 05:04:54 INFO - mozinfo == 0.10 05:04:54 INFO - mozleak == 0.1 05:04:54 INFO - mozlog == 3.5 05:04:54 INFO - moznetwork == 0.27 05:04:54 INFO - mozprocess == 0.25 05:04:54 INFO - mozprofile == 0.28 05:04:54 INFO - mozrunner == 6.13 05:04:54 INFO - mozscreenshot == 0.1 05:04:54 INFO - mozsystemmonitor == 0.3 05:04:54 INFO - moztest == 0.8 05:04:54 INFO - mozversion == 1.4 05:04:54 INFO - psutil == 3.1.1 05:04:54 INFO - requests == 1.2.3 05:04:54 INFO - wsgiref == 0.1.2 05:04:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:54 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')]} 05:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:54 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')]} 05:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:54 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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:54 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 05:04:54 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 05:04:54 INFO - Using env: {'DISPLAY': ':0', 05:04:54 INFO - 'HOME': '/home/cltbld', 05:04:54 INFO - 'LANG': 'en_US.UTF-8', 05:04:54 INFO - 'LANGUAGE': 'en_US:en', 05:04:54 INFO - 'LOGNAME': 'cltbld', 05:04:54 INFO - 'MAIL': '/var/mail/cltbld', 05:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:54 INFO - 'MOZ_NO_REMOTE': '1', 05:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:54 INFO - 'NO_EM_RESTART': '1', 05:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:54 INFO - 'PWD': '/builds/slave/test', 05:04:54 INFO - 'SHELL': '/bin/bash', 05:04:54 INFO - 'SHLVL': '1', 05:04:54 INFO - 'TERM': 'linux', 05:04:54 INFO - 'TMOUT': '86400', 05:04:54 INFO - 'USER': 'cltbld', 05:04:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:54 INFO - '_': '/tools/buildbot/bin/python'} 05:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 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)) 05:04:54 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:04:54 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 05:04:54 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 05:04:54 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 05:04:54 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 05:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:04:56 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)) 05:04:56 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)) 05:04:56 INFO - Installing collected packages: simplejson 05:04:56 INFO - Running setup.py install for simplejson 05:04:56 INFO - building 'simplejson._speedups' extension 05:04:56 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 05:04:57 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 05:04:57 INFO - Successfully installed simplejson 05:04:57 INFO - Cleaning up... 05:04:57 INFO - Return code: 0 05:04:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04: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')]} 05:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04: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')]} 05:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0xc321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfa1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', '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 05:04:57 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 05:04:57 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 05:04:57 INFO - Using env: {'DISPLAY': ':0', 05:04:57 INFO - 'HOME': '/home/cltbld', 05:04:57 INFO - 'LANG': 'en_US.UTF-8', 05:04:57 INFO - 'LANGUAGE': 'en_US:en', 05:04:57 INFO - 'LOGNAME': 'cltbld', 05:04:57 INFO - 'MAIL': '/var/mail/cltbld', 05:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:57 INFO - 'MOZ_NO_REMOTE': '1', 05:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:57 INFO - 'NO_EM_RESTART': '1', 05:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:57 INFO - 'PWD': '/builds/slave/test', 05:04:57 INFO - 'SHELL': '/bin/bash', 05:04:57 INFO - 'SHLVL': '1', 05:04:57 INFO - 'TERM': 'linux', 05:04:57 INFO - 'TMOUT': '86400', 05:04:57 INFO - 'USER': 'cltbld', 05:04:57 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:57 INFO - '_': '/tools/buildbot/bin/python'} 05:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:04:57 INFO - Cleaning up... 05:04:57 INFO - Return code: 0 05:04:57 INFO - Running post-action listener: _resource_record_post_action 05:04:57 INFO - Running post-action listener: _start_resource_monitoring 05:04:57 INFO - Starting resource monitoring. 05:04:57 INFO - [mozharness: 2017-08-08 12:04:57.402367Z] Finished create-virtualenv step (success) 05:04:57 INFO - [mozharness: 2017-08-08 12:04:57.402882Z] Running install step. 05:04:57 INFO - Running pre-action listener: _resource_record_pre_action 05:04:57 INFO - Running main action method: install 05:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:57 INFO - Reading from file tmpfile_stdout 05:04:57 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:57 INFO - Reading from file tmpfile_stdout 05:04:57 INFO - Output received: 05:04:57 INFO - Usage: mozinstall [options] installer 05:04:57 INFO - Options: 05:04:57 INFO - -h, --help show this help message and exit 05:04:57 INFO - -d DEST, --destination=DEST 05:04:57 INFO - Directory to install application into. [default: 05:04:57 INFO - "/builds/slave/test"] 05:04:57 INFO - --app=APP Application being installed. [default: firefox] 05:04:57 INFO - mkdir: /builds/slave/test/build/application 05:04:57 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'] 05:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:05:15 INFO - Reading from file tmpfile_stdout 05:05:15 INFO - Output received: 05:05:15 INFO - /builds/slave/test/build/application/firefox/firefox 05:05:15 INFO - Running post-action listener: _resource_record_post_action 05:05:15 INFO - [mozharness: 2017-08-08 12:05:15.972256Z] Finished install step (success) 05:05:15 INFO - [mozharness: 2017-08-08 12:05:15.972403Z] Running setup-mitmproxy step. 05:05:15 INFO - Running pre-action listener: _resource_record_pre_action 05:05:15 INFO - Running main action method: setup_mitmproxy 05:05:15 INFO - Skipping: mitmproxy is not required 05:05:15 INFO - Running post-action listener: _resource_record_post_action 05:05:15 INFO - [mozharness: 2017-08-08 12:05:15.972948Z] Finished setup-mitmproxy step (success) 05:05:15 INFO - [mozharness: 2017-08-08 12:05:15.973052Z] Running run-tests step. 05:05:15 INFO - Running pre-action listener: _resource_record_pre_action 05:05:15 INFO - Running pre-action listener: _set_gcov_prefix 05:05:15 INFO - Running main action method: run_tests 05:05:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:15 INFO - Python 2.7.3 05:05:15 INFO - Return code: 0 05:05:15 INFO - grabbing minidump binary from tooltool 05:05:15 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')]} 05:05:15 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 0xf9eeb0>, '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 0xf9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfa0240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:15 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 05:05:15 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 05:05:15 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 05:05:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:16 INFO - Return code: 0 05:05:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:05:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:16 INFO - ENV: RUST_BACKTRACE is now 1 05:05:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:05:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:16 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/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 05:05:16 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/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 05:05:16 INFO - Using env: {'DISPLAY': ':0', 05:05:16 INFO - 'HOME': '/home/cltbld', 05:05:16 INFO - 'LANG': 'en_US.UTF-8', 05:05:16 INFO - 'LANGUAGE': 'en_US:en', 05:05:16 INFO - 'LOGNAME': 'cltbld', 05:05:16 INFO - 'MAIL': '/var/mail/cltbld', 05:05:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:16 INFO - 'MOZ_NO_REMOTE': '1', 05:05:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:16 INFO - 'NO_EM_RESTART': '1', 05:05:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:16 INFO - 'PWD': '/builds/slave/test', 05:05:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:16 INFO - 'RUST_BACKTRACE': '1', 05:05:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:05:16 INFO - 'SHELL': '/bin/bash', 05:05:16 INFO - 'SHLVL': '1', 05:05:16 INFO - 'STYLO_FORCE_ENABLED': '1', 05:05:16 INFO - 'TERM': 'linux', 05:05:16 INFO - 'TMOUT': '86400', 05:05:16 INFO - 'USER': 'cltbld', 05:05:16 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225', 05:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:16 INFO - '_': '/tools/buildbot/bin/python'} 05:05:16 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/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 05:05:16 INFO - mozversion application_buildid: 20170808111841 05:05:16 INFO - mozversion application_changeset: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da 05:05:16 INFO - mozversion application_display_name: Nightly 05:05:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:16 INFO - mozversion application_name: Firefox 05:05:16 INFO - mozversion application_remotingname: firefox 05:05:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:16 INFO - mozversion application_vendor: Mozilla 05:05:16 INFO - mozversion application_version: 57.0a1 05:05:16 INFO - mozversion platform_buildid: 20170808111841 05:05:16 INFO - mozversion platform_changeset: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da 05:05:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:16 INFO - mozversion platform_version: 57.0a1 05:05:16 INFO - using testdate: 1502193916 05:05:16 INFO - actual date: 1502193916 05:05:16 INFO - starting webserver on 'localhost:55331' 05:05:16 INFO - SUITE-START | Running 2 tests 05:05:16 INFO - TEST-START | dromaeo_css 05:05:16 INFO - Initialising browser for dromaeo_css test... 05:05:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55331/getInfo.html -profile /tmp/tmpXmWnAW/profile 05:05:16 INFO - TEST-INFO | started process 23456 (/builds/slave/test/build/application/firefox/firefox http://localhost:55331/getInfo.html) 05:05:22 INFO - TEST-INFO | 23456: exit 0 05:05:22 INFO - Browser initialized. 05:05:22 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:22 INFO - TEST-INFO | started process 23683 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXmWnAW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:23 INFO - PID 23683 | 05:05:23 INFO - PID 23683 | (/builds/slave/test/build/application/firefox/firefox:23737): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:05:23 INFO - PID 23683 | 05:05:34 INFO - PID 23683 | RSS: Main: 170631168 05:05:34 INFO - PID 23683 | 05:08:34 INFO - PID 23683 | Cycle 1(1): loaded http://localhost:55331/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55331/tests/dromaeo/cssquery-ext.html) 05:08:35 INFO - PID 23683 | RSS: Main: 175407104 05:08:35 INFO - PID 23683 | 05:11:37 INFO - PID 23683 | Cycle 1(1): loaded http://localhost:55331/tests/dromaeo/cssquery-ext.html (next: http://localhost:55331/tests/dromaeo/cssquery-jquery.html) 05:11:37 INFO - PID 23683 | RSS: Main: 172969984 05:11:37 INFO - PID 23683 | 05:14:39 INFO - PID 23683 | Cycle 1(1): loaded http://localhost:55331/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55331/tests/dromaeo/cssquery-mootools.html) 05:14:40 INFO - PID 23683 | RSS: Main: 175439872 05:14:40 INFO - PID 23683 | 05:17:42 INFO - PID 23683 | Cycle 1(1): loaded http://localhost:55331/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55331/tests/dromaeo/cssquery-prototype.html) 05:17:42 INFO - PID 23683 | RSS: Main: 173846528 05:17:42 INFO - PID 23683 | 05:20:46 INFO - PID 23683 | Cycle 1(1): loaded http://localhost:55331/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55331/tests/dromaeo/cssquery-yui.html) 05:20:47 INFO - PID 23683 | RSS: Main: 176754688 05:20:47 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | Cycle 1(1): loaded http://localhost:55331/tests/dromaeo/cssquery-yui.html (next: http://localhost:55331/tests/dromaeo/cssquery-dojo.html) 05:23:49 INFO - PID 23683 | RSS: Main: 177659904 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | __start_tp_report 05:23:49 INFO - PID 23683 | _x_x_mozilla_page_load 05:23:49 INFO - PID 23683 | _x_x_mozilla_page_load_details 05:23:49 INFO - PID 23683 | |i|pagename|runs| 05:23:49 INFO - PID 23683 | |0;dojo.html;3180.4116238495894;3180.7296379807717;3194.9840250798743;3212.518122281658;3242.870285188591;6215.067739839026;6275.058741188824;6303.275123360815;6304.936950630492;6306.936930630698;6177.289611694657;6261.874762504758;6262.718177682003;6272.466840318567;6272.592281501699;5728.971355143219;5747.942520574793;5763.308402991626;5773.393793651648;5803.477687008169;5798.043322851724;5798.594098413092;5807.30312362515;5810.15752715856;5831.6209446386065;5702.173184888189;5776.104703770922;5779.046457334534;5788.768449262046;5834.183214349994;5517.337919449652;5725;5789.8263052108505;5802.042662960606;5803.8549036274435;5634.746436410372;5646.745896434671;5648.01159797038;5655.434456554352;5693.658380497183;6097.817065488042;6122.816315510542;6258.186435763367;6272.184615999937;6281.905771413432;6582.835429114262;6646.60120392778;6692.732290708366;6699.095622091004;6758.256591774901;6653.966730166367;6732.966335168293;6750.257471678112;6774.220964589037;6776.966115169393;6294.716737746812;6317.084022816712;6323.272823625296;6350.428461438492;6357.300696923335;6900;6985.126859142607;6989.1962424321855;6989.231184569693;7005.894911576331;6517.674116294166;6582.407583317428;6602.933970660232;6665.966670166618;6694.49791265657;6657.20113586363;6690.665466726644;6706.161729783777;6717.093192419059;6725.529212955046;6490.448311893542;6542.182227221598;6568.113304703804;6570.5729127606555;6577.440917522064;6548.377904099103;6551.279359270477;6579.440747536417;6596.043573681885;6666.833329166712;5362.865928351823;5369.033573956725;5369.4630536946;5370.946290537123;5397.622166448389;4598.402207712976;4659.6505262105475;4661.836835710734;4682.344471773954;4714.15145273854;5790.971045144832;5804.593678442468;5806.651600903875;5840.853978650568;5875.3243377012195;6629.966850165815;6699.866002679919;6732.124823772878;6793.592384456949;6810.62541560219;5760.596758226884;5760.596758226884;5802.361740208574;5810.041343178328;5822.621529600562;5842.298924129132;5859.94140058594;5873.735681894325;5886.676232807236;5888.440598143169;6428.71070801815;6513.902291465632;6541.149650545399;6586.7035983380865;6610.272869984298;6729.0915726376315;6739.359760822714;6808.5914845109455;6820.727170913108;6833.624150671761;6432.614043157426;6518.967405162944;6524.380183882524;6525.967370163119;6579.638119903259;6612.437942774918;6629.038789375417;6632.734690612321;6639.070530125883;6709.698063587248;6735.4274886635185;6830.5218634697085;6858.2455929848675;6870.656467176721;6906.240313565604;6495.0906873036865;6520.576162549419;6585.506087043395;6603.372679595431;6605.339466053549;6468.353164683494;6490.740370385131;6528.412442879973;6575.178102737158;6627.271000190071;6494;6497.155369801895;6509.088727578238;6512.6092434454085;6512.967435162794;6517.511186661114;6551.050097735706;6620.304867988984;6642.169728783902;6646.634435106115;6309.1167236587835;6329.49364050884;6364.4908407326375;6366.522510811615;6467.773627923 05:23:49 INFO - PID 23683 | |1;ext.html;800.8758642410428;819.8852160697503;843.3379796859466;849.6134258912195;1037.258360272405;30794.230144246405;31917.36165276693;42308.365374519366;43032.13935721292;43130.56869431305;43317;43458.7827060865;43746.56253437464;43891.34162987558;43894.56105438944;7706.422018348632;7901.48640338379;7906.881396779024;7912.960435197816;7919.722809701661;7384.963075184616;7431.925680743205;7437.256274372574;7449.292317229854;7456.328930396263;7446.66490007951;7456.850862982737;7458.440616953723;7466.290702383292;7470.290322419389;7411.629418529052;7416.962915185443;7427.591482468462;7439.925600744004;7443.516171448866;7686.5772382518935;7810.843783124363;7816.452848300622;7834.843303133963;7837.529748215125;38462.61537384618;38587.61412385896;38760.80619596912;38832.22335553273;39023;3511.91220219494;3588.910277243064;3590.281943611288;3594.370985077601;3599.5680518337704;31464.213394665087;32441.351172976407;32473;32756.836215819007;32823.6717632823;4958.380202474691;4963.851084467472;4978.82574109908;4987.950120498785;5001.099802035632;202186;202255;202406;202811;203462;49015.264771028465;49030.75484622554;49266.50733492691;49439.25841112386;49587.752061239466;51395.7430212854;51476.742616287425;51563.74218128961;51606.74196628993;51627.22559161616;13496.460141594423;13834.30828458573;16520.75218871718;16657.083860387553;16698.415555455744;13990.160590364407;13997;14005.299735013208;14007.08953917992;14025.228612426414;13191.40638671262;13244.470221191237;13423.463061477627;13433.932830335982;13543.796843047363;5204.635675502678;5366.221897824794;5437.238786569884;5452.890942181134;5518.641288316247;967.6081187119228;989.3569180033036;1006.2905651515811;1011.1405305490274;1016.9186465082776;22682.659760103612;22699.319020429415;22736.772632273798;22981.88509057444;23021.424464388525;1091.1216470740972;1104.6520346090956;1109.140416177469;1112.2047735868894;1113.8997490225756;12243.75512489763;12256.877431225752;12336.198147120409;12396.876031239572;12431.937840310922;234811;236505;237633;237758;238211;56743;56974.715126424104;57191.42808571944;57353.13970290449;57437;1441.5242969819935;1456.2936975567054;1499.7900293958855;1504.458394977807;1506.276987046202;22167.44581385478;22192.556148877255;22300.330990070324;22618.095276188767;22795.430114247276;37810.054748631504;37917.81041094887;37963.620363796006;37981.240375192894;38007.2398552033;196333;196336;196933;197651;197933;35163.82418087893;35383.82308088443;36115.458268126524;36420.81789591035;36713.44929826108;48164;48786.756066219445;49025.26462103;49028.2645760321;49440.25839612337;34462.65537344695;35009;35062;35115.47326790049;35146.82426587851;34398.65601343955;34630.30739385148;34686;34974.30051398907;35035;38362;38524.80737596294;38701.41947870728;38721.80639096787;38823.611763882;32758.836205818818;33136;33144;33693.15767105744;33705.494417583264;23824.21010012138;24017.639735403634;24089.75910240945;24282.392940176636;24360.634590480804 05:23:49 INFO - PID 23683 | |2;jquery.html;599.4185639929268;599.4215581963405;600.9248843894513;605.7213681706412;610.2036841921293;22022.229221977235;23092.76907230927;23276.88361558192;23304.18435354763;23450.64824027637;26345;26813.463730725372;26885.731142688568;26919;26969.595456068175;6577.901331480043;6733;6733.764318248862;6740.595564266159;6742.190937087556;4104.507459104897;6064.787732429366;6682.866342673144;6730.461563074967;6757.7972660820005;4088.121053973395;6602.240742314647;6653.035309880064;6657.167854018247;6664.866702665944;6635.3032931541975;6660.333966603349;6661.666916654148;6671.766488172891;6687.364700353459;6160.322364539897;6170.938290617126;6171.567990240685;6172.814815555541;6185.226846644169;28991.275218119717;29785.70214297851;30313.545296820565;30388.240293992607;30832.53751193734;1885.9905700471547;1959.1086055844557;1959.8726082804571;1961.2155137944935;1966.5771859050374;19355.225790968343;19729.901350493157;19788.109535070813;19874;19898.900505497524;2399.0883464283634;2407.3500154958238;2421.576224160765;2456.2999545129733;2515.861627610481;130178;133803;133958;133989.33005335007;134027;14009.789853152211;14917.40330386794;15120.243987800566;15184.392624294904;15573.454929077428;23679.28962131139;23759.1684291049;23772.52454950891;23800.40498987539;23852.403689907544;10522;10647.14822814173;10731.141508679442;10753.623623173151;10914.399708016135;8709.216170544682;9832.31173817826;9878.160356369644;9951.601935922627;10005.79988400228;8136.796580085545;9429.104235097655;9548.283878709124;9612.66355677548;9672.419654820733;2831.5186418308936;2844.971550284512;2910.88356465739;2918.1683220282116;3002.564628128953;955.5652178259014;975.3562648651856;975.7024107647094;982.2485798364257;987.6790043235977;19580.216791328472;19789.91155486433;19896.60206795856;20064.297749578986;20077;1032.8450732390202;1036.0934182590233;1037.8391349340714;1039.308859608352;1052.3738375666467;11089.83365249522;11238.66284011481;11330.320180789178;11342.546298147981;11435.828462573068;453745;454809;459171;468160;470131;105993;106475.46762266294;107379;109180;109325.45337273422;1170.4966864248286;1178.6876477733408;1212.8180772883961;1213.1568559850818;1213.7633161533415;17487.737683935007;17534.649307014042;17577.736333955258;17963.371282005068;18001.63996720032;51007;52768.73615631897;52991;53598.46401536091;54415;67609;68542.31457685359;68706.65646671735;75774;79329;14798.63003424923;15217.6195595111;15420.074795512304;15420.768688469458;15451;23824;23896.40258993539;24016.51966960616;24184.637230441207;24312.756872431757;18362.449126526226;18661.346852860086;18861.71707424417;19046;19068.141933613297;16441.342346306013;16902.915485422494;17189.226484807965;17248;17532.03573803453;18831.529211769815;19979.20083196656;20013;20122.698159527325;20157.899210503852;14303.570892873231;15017.399304027716;15087.698246034799;15187.544373668808;15278.694426111639;18786.812131878505;18791.90604046971;18936;18956.336528221447;19191.80808191956 05:23:49 INFO - PID 23683 | |3;mootools.html;1378.1937566523584;1420.3892326299688;1430.6494908747359;1432.312490004798;1437.6334034821118;13659.590212293622;13922.095063820845;14276.643083922894;14307.070040447363;14308.356123974407;14476.348564314605;14743.115413075197;14747.115173089622;14759.631009224775;14782.482613108543;2621.0826210826203;2667.186508115024;2685.8254213476157;2686.543287641096;2696.12375977807;2574.0733335999025;2682.8122031457806;2733.521633714102;2735.7948153888437;2747.4505098980185;2650.8409495430237;2656.3890305229806;2682.678078630567;2692.7576518113365;2701.7433343832404;2509.3224829296078;2527.9241622751347;2545.910893118732;2549.4391233928504;2549.643049972996;2538.3780973661387;2557.974420255811;2573.623111635266;2579.858107804089;2587.430765231646;13479.797803032865;13658.180509169482;13658.658533536642;13728.794068088986;13825.51610693631;7621.3521850642765;7852.3325517331095;7911.327537159348;7937.603119843985;7965.7610271691365;5089.262057001751;5174.068667639824;5212.9739351303;5288.524032837063;5296.735163241861;1784.4825000749768;1792.9551761205944;1803.0083454100325;1855.8608104392224;1856.1554492705786;22146.557068858692;22376.440588985242;22412.32763017112;22416.66375004376;22455.775442245696;6313.9368606314265;6551.44312733423;6592;6698.8995165072565;6911.101556797584;15843.287052082684;15853.365865365487;15870.603234918988;15878.761818572731;15887.443939462064;7196.4962452627815;7507;7510.136334321623;7511.8497630047095;7512.47412681118;5825.766969321265;6166.290876549165;6399.424051835357;6405.455536279375;7003.8599228015155;5086.18621020635;5233.2468623010955;5255.132903071027;5305.495977882095;5313.1764576490705;1458.117838707588;1492.604459818149;1527.488291422386;1534.0642208252957;1552.5885640305328;920.5259291464901;937.5780898595523;939.248601119102;950.083169741203;952.6522819170953;7639.770806875803;7665.846683066307;7749.108852482037;7751.302382785577;7792.532448053022;684.6576711644178;693.1855070292386;703.409136325489;710.2435905760349;716.5843810589845;4789.209780386267;5065.214891691794;5500.944990550123;5561.555075593943;6032.336442991267;18501.259949602136;19190.80809191918;19374.70937935904;19459.51351216231;19852;18564.350247741266;18692.252309907723;19141.330053448062;19464.318748844005;19655.50861228452;10018.499075046218;10494.68515944523;10558.683239502981;10606.681799546026;10795.83806242907;8119.675212991414;8200.138985406686;8240.21717936795;8490.872636910575;8619.1811777881;17045.14774261257;17070.914645426692;17482.213300401258;17545.385911493035;17819.376321828673;17077.231524581177;17449.738253926454;17515;17521.912390437967;18165.27338906429;11355.772884542428;11577.07383409342;11624;11638.941805291259;11640.243384180001;12229.816552751894;12498.812517812059;12545.372731363577;12572.308523030955;12579.308138052129;10375.948120259653;10431.269811113149;10529.157667386438;10801.351918884891;10822.729431764268;10391.84412233802;10480.737981550523;10493.317934334244;10689.30519516229;10702.09032232269;9313.39462934907;9449.43303401798;9493;9498.762530936781;9504.42973421597;7009.439244860297;7137.427525251204;7155.499115062104;7387.150477694919;7505.324520793155;7457.2542745724995;7502.774916752506;7536.171021187555;7540.811479713051;7592.240775922363 05:23:49 INFO - PID 23683 | |4;prototype.html;497.59197458084384;501.0055040744122;528.6193940362939;529.1480716047166;532.1087178975215;6220.564560480768;6318.431341179299;6318.5892916960365;6332.525060620449;6366.331535188808;5977.342492325841;6040.8489787755325;6138.355472675372;6163.969180154104;6167.352427995063;4977.651564390494;4979.576735977446;5018.096742586333;5030.144875371196;5064.493550644925;4900.240462728283;4911.582515486188;4967.93830560317;5016.322796422491;5027.597792176617;4809.615230781529;4902.901941961177;4944.134776414131;4955;4960.156773348539;4917.827876024322;4945.554900058976;4957.231629097478;4962.5533701967;4990.550850423461;4409.4047303613925;4410.669199810027;4423.712458690175;4450.666200034978;4464.1741277863375;6041.486473649745;6075.635461872258;6092.5430592705725;6114.296855861544;6173.69131543421;5706.201131841545;5729.5702822288495;5752.453516915928;5764.452377024176;5794.797182098607;6452.419282264619;6492.156019717454;6551.868962620769;6571.901421478682;6585.934140658628;4809.278608208761;4869.951300486985;4870;4885.682430641997;4906;6921.792346229621;6973.442124630017;7046.224915259318;7053.589282143541;7084.43324534036;4964.379452568429;4993.675411098267;5003.224500202475;5040.974795126074;5047.318611987408;5038.521340472649;5117.488251174853;5130.640855140105;5147.485251474897;5153.484651534816;5717.285339332583;5729.713514324267;5743.54051675865;5767.279090113736;5801.477866991991;6588.439982601532;6646.60120392778;6680.164979377578;6717.798466046026;6727.495437842182;1696.0925904640958;1714.659489796419;1723.9741403878952;1749.5538637647574;1766.752654628353;1212.1696637803134;1213.0598785940967;1216.8174773784003;1230.2249582762981;1232.8150777383285;5671.347795003545;5719;5760.481556659921;5762.9914764915975;5842.795502157404;6559.934400656028;6582.76960306387;6700.597964122168;6709.832254193684;6789.388954994074;5775.364709881909;5801.593888427769;5806.738696758656;5834.329052159056;5845.532357411397;3570.517980072657;3623.1159796905463;3650.4341827016856;3652.7443078984215;3662.9633703663153;6474.838129046717;6579.276279609239;6580.2103747549645;6602.933970660328;6605.735770569219;6943.409810166091;6961.129858767654;7036.381632224617;7047.330503602149;7054.2240353561065;6490.740370385226;6493.123428337209;6503.2521260054755;6567.671616419065;6592.143021407186;6717.413795688597;6742.426893713893;6746.898796518154;6748.392644662004;6814.977753336841;6671.966640166768;6741.898871516833;6785.830354241183;6793.671101160495;6846.7945961621235;6516.837079073061;6532.346765323429;6534.16471810866;6574.506911981525;6609.504287178384;4976.552110310089;5006.799728010838;5021.819872330073;5031.119554077948;5034.295198672262;6864.553804002724;6868.62222577763;6926.480513961574;6937.618430986344;6955.478339124687;6566.606003639797;6582.210134783857;6620.3710647488415;6623.503237257129;6635.777014700129;6444.742210311535;6495.76580449713;6557.868842623026;6610.272869984202;6631.303713110054;6141.84645383869;6295.18162638854;6396.552241343062;6420.775272865428;6462.450691691259;6552.410283074546;6557.639329836905;6571.605703657605;6626.801195964129;6628.867422651617;6305.148804911279;6332.493400528039;6335.081413195153;6346.492280617447;6403.583767055126 05:23:49 INFO - PID 23683 | |5;yui.html;784.1726978038171;788.259036505685;814.8207394373239;903.268352634366;929.9302552308576;18399.72400413993;19183.71224431631;19268.99999999998;19269.71095433568;19272.3254686086;20628.587428251427;21369.57260854782;21542.030608622612;21566.568668626616;21716.34850954474;961.8268711631921;1006.9194464442863;1009.6314845081566;1015.5734591471563;1015.7562185075566;973.8198433289864;988.461288597716;990.9851352229722;998.32612986234;1001.3841487485217;1053.3521884041297;1057.973550661232;1058.8941105889426;1059.2479339668805;1059.6503153959213;1062.1502797761798;1062.6387028410338;1064.0795711709393;1064.2656566968806;1064.6805958212583;776.456480463672;788.3811208201535;788.4835432791531;788.5071830106184;788.8067423481284;18089.819101809077;18798.154083066296;19691.40925772229;20094.89952550228;20155.0930208141;20252.189912403486;20392.694109588367;20651.58696826055;20685.069171887302;21214.151433942625;16382.344706211738;16606.916965415097;16711.66576668472;16864.494065178063;16875.409360672442;3596.4425514045365;3644.671979521856;3659.0852286928266;3665.17533554948;3675.8345874435713;110636.44681776539;113035.43482282618;117467.412662937;125446;126955;16517.421890234025;17637;17680.381186658404;17682.469525914243;17689.557761056076;31496.685033149377;31695;32401.675983239867;32488.675113248566;32489.51265731016;8466.32269418445;8585.31317494599;8649.827003460021;8665.566721664018;8668.653253869901;4290.613844753924;4292.527821939584;4317.935230971538;4355.107203023373;4395.450568678915;5240.895182096337;5287.41838397776;5327.280817089721;5344.43883392246;5373.3551973762615;1072.5334479501441;1099.0712847643729;1100.5927806711568;1108.9852784702068;1109.9556017759198;573.7518424358736;694.2571448550104;716.3445447415661;720.6216736213426;737.1449119021909;12139.210951288138;12590.37048147589;12692.301923394301;12836.871631283755;13341.19952802835;683.8427161752725;696.7422053840011;697.7522979342361;705.8376573388149;712.9465290103263;5408.1887716842;5577.860553486195;5651.321841378978;5786.392428795;5844.298684157929;106874.46562767136;109848;110994;112613;116184;89596.5520172408;92935;97813;98168;99160;80393;82128.58935705402;82646.76029859557;82748.58625706832;83978.58010709907;11851.466683999413;11995.82006269924;12027.4587643558;12107.75784484323;12430.378481076092;33127.503087453224;34228.315433691685;34650.96047118591;36383.636163638024;37105.81447092747;63432;64843;65103.67448162729;66311.33688663051;69136.65431672809;18710.345137920107;19078.141483633546;19218.135183916478;19274.32539861098;19633.70549441788;19189.712154317418;19204.903975480032;19286.61426771485;19409.70885436748;19489.317873874345;19070.809291906902;19237.903810480857;19430.417087487396;19472.123754430802;19766.40700778979;16782.832171678616;16817.41139060127;16889.91555042217;17066.31734730597;17126.657466850345;18309.725354119964;18693.532661683566;19298.80701193026;19434.514137146685;19454.51363715862;9337;9443;9546.665866694633;9547;9547.283953703361;14152.43390264378;14320.856791432368;14397.087063870282;14595.416183352547;14912.701745965236 05:23:49 INFO - PID 23683 | __end_tp_report 05:23:49 INFO - PID 23683 | __start_cc_report 05:23:49 INFO - PID 23683 | _x_x_mozilla_cycle_collect,369 05:23:49 INFO - PID 23683 | __end_cc_report 05:23:49 INFO - PID 23683 | __startTimestamp1502195029434__endTimestamp 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | ------- Summary: start ------- 05:23:49 INFO - PID 23683 | Number of tests: 6 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | [#0] dojo.html Cycles:165 Average:6181.36 Median:6468.35 stddev:727.37 (11.2%) stddev-sans-first:690.45 05:23:49 INFO - PID 23683 | Values: 3180.4 3180.7 3195.0 3212.5 3242.9 6215.1 6275.1 6303.3 6304.9 6306.9 6177.3 6261.9 6262.7 6272.5 6272.6 5729.0 5747.9 5763.3 5773.4 5803.5 5798.0 5798.6 5807.3 5810.2 5831.6 5702.2 5776.1 5779.0 5788.8 5834.2 5517.3 5725.0 5789.8 5802.0 5803.9 5634.7 5646.7 5648.0 5655.4 5693.7 6097.8 6122.8 6258.2 6272.2 6281.9 6582.8 6646.6 6692.7 6699.1 6758.3 6654.0 6733.0 6750.3 6774.2 6777.0 6294.7 6317.1 6323.3 6350.4 6357.3 6900.0 6985.1 6989.2 6989.2 7005.9 6517.7 6582.4 6602.9 6666.0 6694.5 6657.2 6690.7 6706.2 6717.1 6725.5 6490.4 6542.2 6568.1 6570.6 6577.4 6548.4 6551.3 6579.4 6596.0 6666.8 5362.9 5369.0 5369.5 5370.9 5397.6 4598.4 4659.7 4661.8 4682.3 4714.2 5791.0 5804.6 5806.7 5840.9 5875.3 6630.0 6699.9 6732.1 6793.6 6810.6 5760.6 5760.6 5802.4 5810.0 5822.6 5842.3 5859.9 5873.7 5886.7 5888.4 6428.7 6513.9 6541.1 6586.7 6610.3 6729.1 6739.4 6808.6 6820.7 6833.6 6432.6 6519.0 6524.4 6526.0 6579.6 6612.4 6629.0 6632.7 6639.1 6709.7 6735.4 6830.5 6858.2 6870.7 6906.2 6495.1 6520.6 6585.5 6603.4 6605.3 6468.4 6490.7 6528.4 6575.2 6627.3 6494.0 6497.2 6509.1 6512.6 6513.0 6517.5 6551.1 6620.3 6642.2 6646.6 6309.1 6329.5 6364.5 6366.5 6467.8 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | [#1] ext.html Cycles:180 Average:38691.86 Median:3592.33 stddev:55263.03 (1538.4%) stddev-sans-first:55344.82 05:23:49 INFO - PID 23683 | Values: 800.9 819.9 843.3 849.6 1037.3 30794.2 31917.4 42308.4 43032.1 43130.6 43317.0 43458.8 43746.6 43891.3 43894.6 7706.4 7901.5 7906.9 7913.0 7919.7 7385.0 7431.9 7437.3 7449.3 7456.3 7446.7 7456.9 7458.4 7466.3 7470.3 7411.6 7417.0 7427.6 7439.9 7443.5 7686.6 7810.8 7816.5 7834.8 7837.5 38462.6 38587.6 38760.8 38832.2 39023.0 3511.9 3588.9 3590.3 3594.4 3599.6 31464.2 32441.4 32473.0 32756.8 32823.7 4958.4 4963.9 4978.8 4988.0 5001.1 202186.0 202255.0 202406.0 202811.0 203462.0 49015.3 49030.8 49266.5 49439.3 49587.8 51395.7 51476.7 51563.7 51606.7 51627.2 13496.5 13834.3 16520.8 16657.1 16698.4 13990.2 13997.0 14005.3 14007.1 14025.2 13191.4 13244.5 13423.5 13433.9 13543.8 5204.6 5366.2 5437.2 5452.9 5518.6 967.6 989.4 1006.3 1011.1 1016.9 22682.7 22699.3 22736.8 22981.9 23021.4 1091.1 1104.7 1109.1 1112.2 1113.9 12243.8 12256.9 12336.2 12396.9 12431.9 234811.0 236505.0 237633.0 237758.0 238211.0 56743.0 56974.7 57191.4 57353.1 57437.0 1441.5 1456.3 1499.8 1504.5 1506.3 22167.4 22192.6 22300.3 22618.1 22795.4 37810.1 37917.8 37963.6 37981.2 38007.2 196333.0 196336.0 196933.0 197651.0 197933.0 35163.8 35383.8 36115.5 36420.8 36713.4 48164.0 48786.8 49025.3 49028.3 49440.3 34462.7 35009.0 35062.0 35115.5 35146.8 34398.7 34630.3 34686.0 34974.3 35035.0 38362.0 38524.8 38701.4 38721.8 38823.6 32758.8 33136.0 33144.0 33693.2 33705.5 23824.2 24017.6 24089.8 24282.4 24360.6 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | [#2] jquery.html Cycles:180 Average:33768.09 Median:23564.97 stddev:77706.63 (329.8%) stddev-sans-first:77884.71 05:23:49 INFO - PID 23683 | Values: 599.4 599.4 600.9 605.7 610.2 22022.2 23092.8 23276.9 23304.2 23450.6 26345.0 26813.5 26885.7 26919.0 26969.6 6577.9 6733.0 6733.8 6740.6 6742.2 4104.5 6064.8 6682.9 6730.5 6757.8 4088.1 6602.2 6653.0 6657.2 6664.9 6635.3 6660.3 6661.7 6671.8 6687.4 6160.3 6170.9 6171.6 6172.8 6185.2 28991.3 29785.7 30313.5 30388.2 30832.5 1886.0 1959.1 1959.9 1961.2 1966.6 19355.2 19729.9 19788.1 19874.0 19898.9 2399.1 2407.4 2421.6 2456.3 2515.9 130178.0 133803.0 133958.0 133989.3 134027.0 14009.8 14917.4 15120.2 15184.4 15573.5 23679.3 23759.2 23772.5 23800.4 23852.4 10522.0 10647.1 10731.1 10753.6 10914.4 8709.2 9832.3 9878.2 9951.6 10005.8 8136.8 9429.1 9548.3 9612.7 9672.4 2831.5 2845.0 2910.9 2918.2 3002.6 955.6 975.4 975.7 982.2 987.7 19580.2 19789.9 19896.6 20064.3 20077.0 1032.8 1036.1 1037.8 1039.3 1052.4 11089.8 11238.7 11330.3 11342.5 11435.8 453745.0 454809.0 459171.0 468160.0 470131.0 105993.0 106475.5 107379.0 109180.0 109325.5 1170.5 1178.7 1212.8 1213.2 1213.8 17487.7 17534.6 17577.7 17963.4 18001.6 51007.0 52768.7 52991.0 53598.5 54415.0 67609.0 68542.3 68706.7 75774.0 79329.0 14798.6 15217.6 15420.1 15420.8 15451.0 23824.0 23896.4 24016.5 24184.6 24312.8 18362.4 18661.3 18861.7 19046.0 19068.1 16441.3 16902.9 17189.2 17248.0 17532.0 18831.5 19979.2 20013.0 20122.7 20157.9 14303.6 15017.4 15087.7 15187.5 15278.7 18786.8 18791.9 18936.0 18956.3 19191.8 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | [#3] mootools.html Cycles:180 Average:8712.03 Median:2553.81 stddev:5905.90 (231.3%) stddev-sans-first:5896.76 05:23:49 INFO - PID 23683 | Values: 1378.2 1420.4 1430.6 1432.3 1437.6 13659.6 13922.1 14276.6 14307.1 14308.4 14476.3 14743.1 14747.1 14759.6 14782.5 2621.1 2667.2 2685.8 2686.5 2696.1 2574.1 2682.8 2733.5 2735.8 2747.5 2650.8 2656.4 2682.7 2692.8 2701.7 2509.3 2527.9 2545.9 2549.4 2549.6 2538.4 2558.0 2573.6 2579.9 2587.4 13479.8 13658.2 13658.7 13728.8 13825.5 7621.4 7852.3 7911.3 7937.6 7965.8 5089.3 5174.1 5213.0 5288.5 5296.7 1784.5 1793.0 1803.0 1855.9 1856.2 22146.6 22376.4 22412.3 22416.7 22455.8 6313.9 6551.4 6592.0 6698.9 6911.1 15843.3 15853.4 15870.6 15878.8 15887.4 7196.5 7507.0 7510.1 7511.8 7512.5 5825.8 6166.3 6399.4 6405.5 7003.9 5086.2 5233.2 5255.1 5305.5 5313.2 1458.1 1492.6 1527.5 1534.1 1552.6 920.5 937.6 939.2 950.1 952.7 7639.8 7665.8 7749.1 7751.3 7792.5 684.7 693.2 703.4 710.2 716.6 4789.2 5065.2 5500.9 5561.6 6032.3 18501.3 19190.8 19374.7 19459.5 19852.0 18564.4 18692.3 19141.3 19464.3 19655.5 10018.5 10494.7 10558.7 10606.7 10795.8 8119.7 8200.1 8240.2 8490.9 8619.2 17045.1 17070.9 17482.2 17545.4 17819.4 17077.2 17449.7 17515.0 17521.9 18165.3 11355.8 11577.1 11624.0 11638.9 11640.2 12229.8 12498.8 12545.4 12572.3 12579.3 10375.9 10431.3 10529.2 10801.4 10822.7 10391.8 10480.7 10493.3 10689.3 10702.1 9313.4 9449.4 9493.0 9498.8 9504.4 7009.4 7137.4 7155.5 7387.2 7505.3 7457.3 7502.8 7536.2 7540.8 7592.2 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | [#4] prototype.html Cycles:180 Average:5538.14 Median:6126.33 stddev:1564.57 (25.5%) stddev-sans-first:1522.53 05:23:49 INFO - PID 23683 | Values: 497.6 501.0 528.6 529.1 532.1 6220.6 6318.4 6318.6 6332.5 6366.3 5977.3 6040.8 6138.4 6164.0 6167.4 4977.7 4979.6 5018.1 5030.1 5064.5 4900.2 4911.6 4967.9 5016.3 5027.6 4809.6 4902.9 4944.1 4955.0 4960.2 4917.8 4945.6 4957.2 4962.6 4990.6 4409.4 4410.7 4423.7 4450.7 4464.2 6041.5 6075.6 6092.5 6114.3 6173.7 5706.2 5729.6 5752.5 5764.5 5794.8 6452.4 6492.2 6551.9 6571.9 6585.9 4809.3 4870.0 4870.0 4885.7 4906.0 6921.8 6973.4 7046.2 7053.6 7084.4 4964.4 4993.7 5003.2 5041.0 5047.3 5038.5 5117.5 5130.6 5147.5 5153.5 5717.3 5729.7 5743.5 5767.3 5801.5 6588.4 6646.6 6680.2 6717.8 6727.5 1696.1 1714.7 1724.0 1749.6 1766.8 1212.2 1213.1 1216.8 1230.2 1232.8 5671.3 5719.0 5760.5 5763.0 5842.8 6559.9 6582.8 6700.6 6709.8 6789.4 5775.4 5801.6 5806.7 5834.3 5845.5 3570.5 3623.1 3650.4 3652.7 3663.0 6474.8 6579.3 6580.2 6602.9 6605.7 6943.4 6961.1 7036.4 7047.3 7054.2 6490.7 6493.1 6503.3 6567.7 6592.1 6717.4 6742.4 6746.9 6748.4 6815.0 6672.0 6741.9 6785.8 6793.7 6846.8 6516.8 6532.3 6534.2 6574.5 6609.5 4976.6 5006.8 5021.8 5031.1 5034.3 6864.6 6868.6 6926.5 6937.6 6955.5 6566.6 6582.2 6620.4 6623.5 6635.8 6444.7 6495.8 6557.9 6610.3 6631.3 6141.8 6295.2 6396.6 6420.8 6462.5 6552.4 6557.6 6571.6 6626.8 6628.9 6305.1 6332.5 6335.1 6346.5 6403.6 05:23:49 INFO - PID 23683 | 05:23:49 INFO - PID 23683 | [#5] yui.html Cycles:180 Average:23163.82 Median:20322.44 stddev:31039.69 (152.7%) stddev-sans-first:31081.27 05:23:49 INFO - PID 23683 | Values: 784.2 788.3 814.8 903.3 929.9 18399.7 19183.7 19269.0 19269.7 19272.3 20628.6 21369.6 21542.0 21566.6 21716.3 961.8 1006.9 1009.6 1015.6 1015.8 973.8 988.5 991.0 998.3 1001.4 1053.4 1058.0 1058.9 1059.2 1059.7 1062.2 1062.6 1064.1 1064.3 1064.7 776.5 788.4 788.5 788.5 788.8 18089.8 18798.2 19691.4 20094.9 20155.1 20252.2 20392.7 20651.6 20685.1 21214.2 16382.3 16606.9 16711.7 16864.5 16875.4 3596.4 3644.7 3659.1 3665.2 3675.8 110636.4 113035.4 117467.4 125446.0 126955.0 16517.4 17637.0 17680.4 17682.5 17689.6 31496.7 31695.0 32401.7 32488.7 32489.5 8466.3 8585.3 8649.8 8665.6 8668.7 4290.6 4292.5 4317.9 4355.1 4395.5 5240.9 5287.4 5327.3 5344.4 5373.4 1072.5 1099.1 1100.6 1109.0 1110.0 573.8 694.3 716.3 720.6 737.1 12139.2 12590.4 12692.3 12836.9 13341.2 683.8 696.7 697.8 705.8 712.9 5408.2 5577.9 5651.3 5786.4 5844.3 106874.5 109848.0 110994.0 112613.0 116184.0 89596.6 92935.0 97813.0 98168.0 99160.0 80393.0 82128.6 82646.8 82748.6 83978.6 11851.5 11995.8 12027.5 12107.8 12430.4 33127.5 34228.3 34651.0 36383.6 37105.8 63432.0 64843.0 65103.7 66311.3 69136.7 18710.3 19078.1 19218.1 19274.3 19633.7 19189.7 19204.9 19286.6 19409.7 19489.3 19070.8 19237.9 19430.4 19472.1 19766.4 16782.8 16817.4 16889.9 17066.3 17126.7 18309.7 18693.5 19298.8 19434.5 19454.5 9337.0 9443.0 9546.7 9547.0 9547.3 14152.4 14320.9 14397.1 14595.4 14912.7 05:23:49 INFO - PID 23683 | -------- Summary: end -------- 05:23:49 INFO - PID 23683 | 05:23:50 INFO - TEST-INFO | 23683: exit 0 05:23:50 INFO - TEST-OK | dromaeo_css | took 1113791ms 05:23:50 INFO - TEST-START | kraken 05:23:50 INFO - Initialising browser for kraken test... 05:23:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55331/getInfo.html -profile /tmp/tmp0IrM0c/profile 05:23:50 INFO - TEST-INFO | started process 24057 (/builds/slave/test/build/application/firefox/firefox http://localhost:55331/getInfo.html) 05:23:56 INFO - TEST-INFO | 24057: exit 0 05:23:56 INFO - Browser initialized. 05:23:56 INFO - Running cycle 1/1 for kraken test... 05:23:56 INFO - TEST-INFO | started process 24283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0IrM0c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:57 INFO - PID 24283 | 05:23:57 INFO - PID 24283 | (/builds/slave/test/build/application/firefox/firefox:24338): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:23:57 INFO - PID 24283 | 05:24:08 INFO - PID 24283 | RSS: Main: 170680320 05:24:08 INFO - PID 24283 | 05:24:10 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55331/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:11 INFO - PID 24283 | RSS: Main: 175349760 05:24:11 INFO - PID 24283 | 05:24:14 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55331/tests/kraken/driver.html?testName=audio-dft) 05:24:15 INFO - PID 24283 | RSS: Main: 176140288 05:24:15 INFO - PID 24283 | 05:24:18 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55331/tests/kraken/driver.html?testName=audio-fft) 05:24:18 INFO - PID 24283 | RSS: Main: 170668032 05:24:18 INFO - PID 24283 | 05:24:20 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55331/tests/kraken/driver.html?testName=audio-oscillator) 05:24:21 INFO - PID 24283 | RSS: Main: 170917888 05:24:21 INFO - PID 24283 | 05:24:23 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55331/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:24 INFO - PID 24283 | RSS: Main: 171900928 05:24:24 INFO - PID 24283 | 05:24:27 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55331/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:28 INFO - PID 24283 | RSS: Main: 174182400 05:24:28 INFO - PID 24283 | 05:24:31 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55331/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:32 INFO - PID 24283 | RSS: Main: 173912064 05:24:32 INFO - PID 24283 | 05:24:35 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55331/tests/kraken/driver.html?testName=json-parse-financial) 05:24:35 INFO - PID 24283 | RSS: Main: 174379008 05:24:35 INFO - PID 24283 | 05:24:37 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55331/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:38 INFO - PID 24283 | RSS: Main: 174620672 05:24:38 INFO - PID 24283 | 05:24:40 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:40 INFO - PID 24283 | RSS: Main: 174927872 05:24:40 INFO - PID 24283 | 05:24:42 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:43 INFO - PID 24283 | RSS: Main: 174514176 05:24:43 INFO - PID 24283 | 05:24:46 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:47 INFO - PID 24283 | RSS: Main: 174968832 05:24:47 INFO - PID 24283 | 05:24:50 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:24:50 INFO - PID 24283 | RSS: Main: 174780416 05:24:50 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | Cycle 1(1): loaded http://localhost:55331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55331/tests/kraken/driver.html?testName=ai-astar) 05:24:52 INFO - PID 24283 | RSS: Main: 176320512 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | __start_tp_report 05:24:52 INFO - PID 24283 | _x_x_mozilla_page_load 05:24:52 INFO - PID 24283 | _x_x_mozilla_page_load_details 05:24:52 INFO - PID 24283 | |i|pagename|runs| 05:24:52 INFO - PID 24283 | |0;ai-astar;103;114;103;107;110;111;107;100;109;110 05:24:52 INFO - PID 24283 | |1;audio-beat-detection;147;120;156;179;122;151;144;123;176;129 05:24:52 INFO - PID 24283 | |2;audio-dft;167;161;164;161;162;171;166;163;162;166 05:24:52 INFO - PID 24283 | |3;audio-fft;74;76;75;174;75;79;81;86;148;80 05:24:52 INFO - PID 24283 | |4;audio-oscillator;79;78;131;78;78;79;129;85;79;139 05:24:52 INFO - PID 24283 | |5;imaging-gaussian-blur;114;114;114;114;116;114;114;115;114;115 05:24:52 INFO - PID 24283 | |6;imaging-darkroom;151;155;158;152;150;150;151;150;150;150 05:24:52 INFO - PID 24283 | |7;imaging-desaturate;95;94;99;98;95;94;94;96;94;94 05:24:52 INFO - PID 24283 | |8;json-parse-financial;55;64;54;54;55;54;54;58;54;55 05:24:52 INFO - PID 24283 | |9;json-stringify-tinderbox;53;53;54;53;65;54;53;55;53;54 05:24:52 INFO - PID 24283 | |10;stanford-crypto-aes;78;88;81;78;78;78;80;86;88;76 05:24:52 INFO - PID 24283 | |11;stanford-crypto-ccm;143;138;137;147;126;142;136;141;137;176 05:24:52 INFO - PID 24283 | |12;stanford-crypto-pbkdf2;175;180;180;182;198;175;180;183;177;185 05:24:52 INFO - PID 24283 | |13;stanford-crypto-sha256-iterative;70;68;78;68;66;64;67;67;68;67 05:24:52 INFO - PID 24283 | __end_tp_report 05:24:52 INFO - PID 24283 | __start_cc_report 05:24:52 INFO - PID 24283 | _x_x_mozilla_cycle_collect,948 05:24:52 INFO - PID 24283 | __end_cc_report 05:24:52 INFO - PID 24283 | __startTimestamp1502195092953__endTimestamp 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | ------- Summary: start ------- 05:24:52 INFO - PID 24283 | Number of tests: 14 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#0] ai-astar Cycles:10 Average:107.40 Median:109.50 stddev:4.30 (3.9%) stddev-sans-first:4.26 05:24:52 INFO - PID 24283 | Values: 103.0 114.0 103.0 107.0 110.0 111.0 107.0 100.0 109.0 110.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:149.00 stddev:21.53 (14.5%) stddev-sans-first:22.82 05:24:52 INFO - PID 24283 | Values: 147.0 120.0 156.0 179.0 122.0 151.0 144.0 123.0 176.0 129.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#2] audio-dft Cycles:10 Average:164.30 Median:165.00 stddev:3.20 (1.9%) stddev-sans-first:3.24 05:24:52 INFO - PID 24283 | Values: 167.0 161.0 164.0 161.0 162.0 171.0 166.0 163.0 162.0 166.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#3] audio-fft Cycles:10 Average:94.80 Median:77.50 stddev:35.61 (45.9%) stddev-sans-first:36.96 05:24:52 INFO - PID 24283 | Values: 74.0 76.0 75.0 174.0 75.0 79.0 81.0 86.0 148.0 80.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:78.50 stddev:26.08 (33.2%) stddev-sans-first:26.97 05:24:52 INFO - PID 24283 | Values: 79.0 78.0 131.0 78.0 78.0 79.0 129.0 85.0 79.0 139.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 05:24:52 INFO - PID 24283 | Values: 114.0 114.0 114.0 114.0 116.0 114.0 114.0 115.0 114.0 115.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:151.00 stddev:2.71 (1.8%) stddev-sans-first:2.86 05:24:52 INFO - PID 24283 | Values: 151.0 155.0 158.0 152.0 150.0 150.0 151.0 150.0 150.0 150.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:1.83 (1.9%) stddev-sans-first:1.94 05:24:52 INFO - PID 24283 | Values: 95.0 94.0 99.0 98.0 95.0 94.0 94.0 96.0 94.0 94.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:3.16 (5.8%) stddev-sans-first:3.35 05:24:52 INFO - PID 24283 | Values: 55.0 64.0 54.0 54.0 55.0 54.0 54.0 58.0 54.0 55.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:54.00 stddev:3.68 (6.8%) stddev-sans-first:3.86 05:24:52 INFO - PID 24283 | Values: 53.0 53.0 54.0 53.0 65.0 54.0 53.0 55.0 53.0 54.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:80.50 stddev:4.53 (5.6%) stddev-sans-first:4.67 05:24:52 INFO - PID 24283 | Values: 78.0 88.0 81.0 78.0 78.0 78.0 80.0 86.0 88.0 76.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:141.50 stddev:13.08 (9.2%) stddev-sans-first:13.87 05:24:52 INFO - PID 24283 | Values: 143.0 138.0 137.0 147.0 126.0 142.0 136.0 141.0 137.0 176.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:181.00 stddev:6.65 (3.7%) stddev-sans-first:6.63 05:24:52 INFO - PID 24283 | Values: 175.0 180.0 180.0 182.0 198.0 175.0 180.0 183.0 177.0 185.0 05:24:52 INFO - PID 24283 | 05:24:52 INFO - PID 24283 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:3.74 (5.5%) stddev-sans-first:3.92 05:24:52 INFO - PID 24283 | Values: 70.0 68.0 78.0 68.0 66.0 64.0 67.0 67.0 68.0 67.0 05:24:52 INFO - PID 24283 | -------- Summary: end -------- 05:24:52 INFO - PID 24283 | 05:24:53 INFO - PID 24283 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:24:53 INFO - TEST-INFO | 24283: exit 0 05:24:53 INFO - TEST-OK | kraken | took 63558ms 05:24:53 INFO - SUITE-END | took 1177s 05:24:54 INFO - Completed test suite (00:19:37) 05:24:54 INFO - javascript benchmark 05:24:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8492.663682762186, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3180.4116238495894, 3180.7296379807717, 3194.9840250798743, 3212.518122281658, 3242.870285188591, 6215.067739839026, 6275.058741188824, 6303.275123360815, 6304.936950630492, 6306.936930630698, 6177.289611694657, 6261.874762504758, 6262.718177682003, 6272.466840318567, 6272.592281501699, 5728.971355143219, 5747.942520574793, 5763.308402991626, 5773.393793651648, 5803.477687008169, 5798.043322851724, 5798.594098413092, 5807.30312362515, 5810.15752715856, 5831.6209446386065, 5702.173184888189, 5776.104703770922, 5779.046457334534, 5788.768449262046, 5834.183214349994, 5517.337919449652, 5725.0, 5789.8263052108505, 5802.042662960606, 5803.8549036274435, 5634.746436410372, 5646.745896434671, 5648.01159797038, 5655.434456554352, 5693.658380497183, 6097.817065488042, 6122.816315510542, 6258.186435763367, 6272.184615999937, 6281.905771413432, 6582.835429114262, 6646.60120392778, 6692.732290708366, 6699.095622091004, 6758.256591774901, 6653.966730166367, 6732.966335168293, 6750.257471678112, 6774.220964589037, 6776.966115169393, 6294.716737746812, 6317.084022816712, 6323.272823625296, 6350.428461438492, 6357.300696923335, 6900.0, 6985.126859142607, 6989.1962424321855, 6989.231184569693, 7005.894911576331, 6517.674116294166, 6582.407583317428, 6602.933970660232, 6665.966670166618, 6694.49791265657, 6657.20113586363, 6690.665466726644, 6706.161729783777, 6717.093192419059, 6725.529212955046, 6490.448311893542, 6542.182227221598, 6568.113304703804, 6570.5729127606555, 6577.440917522064, 6548.377904099103, 6551.279359270477, 6579.440747536417, 6596.043573681885, 6666.833329166712, 5362.865928351823, 5369.033573956725, 5369.4630536946, 5370.946290537123, 5397.622166448389, 4598.402207712976, 4659.6505262105475, 4661.836835710734, 4682.344471773954, 4714.15145273854, 5790.971045144832, 5804.593678442468, 5806.651600903875, 5840.853978650568, 5875.3243377012195, 6629.966850165815, 6699.866002679919, 6732.124823772878, 6793.592384456949, 6810.62541560219, 5760.596758226884, 5760.596758226884, 5802.361740208574, 5810.041343178328, 5822.621529600562, 5842.298924129132, 5859.94140058594, 5873.735681894325, 5886.676232807236, 5888.440598143169, 6428.71070801815, 6513.902291465632, 6541.149650545399, 6586.7035983380865, 6610.272869984298, 6729.0915726376315, 6739.359760822714, 6808.5914845109455, 6820.727170913108, 6833.624150671761, 6432.614043157426, 6518.967405162944, 6524.380183882524, 6525.967370163119, 6579.638119903259, 6612.437942774918, 6629.038789375417, 6632.734690612321, 6639.070530125883, 6709.698063587248, 6735.4274886635185, 6830.5218634697085, 6858.2455929848675, 6870.656467176721, 6906.240313565604, 6495.0906873036865, 6520.576162549419, 6585.506087043395, 6603.372679595431, 6605.339466053549, 6468.353164683494, 6490.740370385131, 6528.412442879973, 6575.178102737158, 6627.271000190071, 6494.0, 6497.155369801895, 6509.088727578238, 6512.6092434454085, 6512.967435162794, 6517.511186661114, 6551.050097735706, 6620.304867988984, 6642.169728783902, 6646.634435106115, 6309.1167236587835, 6329.49364050884, 6364.4908407326375, 6366.522510811615, 6467.773627923], "value": 6127.1779397448045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.8758642410428, 819.8852160697503, 843.3379796859466, 849.6134258912195, 1037.258360272405, 30794.230144246405, 31917.36165276693, 42308.365374519366, 43032.13935721292, 43130.56869431305, 43317.0, 43458.7827060865, 43746.56253437464, 43891.34162987558, 43894.56105438944, 7706.422018348632, 7901.48640338379, 7906.881396779024, 7912.960435197816, 7919.722809701661, 7384.963075184616, 7431.925680743205, 7437.256274372574, 7449.292317229854, 7456.328930396263, 7446.66490007951, 7456.850862982737, 7458.440616953723, 7466.290702383292, 7470.290322419389, 7411.629418529052, 7416.962915185443, 7427.591482468462, 7439.925600744004, 7443.516171448866, 7686.5772382518935, 7810.843783124363, 7816.452848300622, 7834.843303133963, 7837.529748215125, 38462.61537384618, 38587.61412385896, 38760.80619596912, 38832.22335553273, 39023.0, 3511.91220219494, 3588.910277243064, 3590.281943611288, 3594.370985077601, 3599.5680518337704, 31464.213394665087, 32441.351172976407, 32473.0, 32756.836215819007, 32823.6717632823, 4958.380202474691, 4963.851084467472, 4978.82574109908, 4987.950120498785, 5001.099802035632, 202186.0, 202255.0, 202406.0, 202811.0, 203462.0, 49015.264771028465, 49030.75484622554, 49266.50733492691, 49439.25841112386, 49587.752061239466, 51395.7430212854, 51476.742616287425, 51563.74218128961, 51606.74196628993, 51627.22559161616, 13496.460141594423, 13834.30828458573, 16520.75218871718, 16657.083860387553, 16698.415555455744, 13990.160590364407, 13997.0, 14005.299735013208, 14007.08953917992, 14025.228612426414, 13191.40638671262, 13244.470221191237, 13423.463061477627, 13433.932830335982, 13543.796843047363, 5204.635675502678, 5366.221897824794, 5437.238786569884, 5452.890942181134, 5518.641288316247, 967.6081187119228, 989.3569180033036, 1006.2905651515811, 1011.1405305490274, 1016.9186465082776, 22682.659760103612, 22699.319020429415, 22736.772632273798, 22981.88509057444, 23021.424464388525, 1091.1216470740972, 1104.6520346090956, 1109.140416177469, 1112.2047735868894, 1113.8997490225756, 12243.75512489763, 12256.877431225752, 12336.198147120409, 12396.876031239572, 12431.937840310922, 234811.0, 236505.0, 237633.0, 237758.0, 238211.0, 56743.0, 56974.715126424104, 57191.42808571944, 57353.13970290449, 57437.0, 1441.5242969819935, 1456.2936975567054, 1499.7900293958855, 1504.458394977807, 1506.276987046202, 22167.44581385478, 22192.556148877255, 22300.330990070324, 22618.095276188767, 22795.430114247276, 37810.054748631504, 37917.81041094887, 37963.620363796006, 37981.240375192894, 38007.2398552033, 196333.0, 196336.0, 196933.0, 197651.0, 197933.0, 35163.82418087893, 35383.82308088443, 36115.458268126524, 36420.81789591035, 36713.44929826108, 48164.0, 48786.756066219445, 49025.26462103, 49028.2645760321, 49440.25839612337, 34462.65537344695, 35009.0, 35062.0, 35115.47326790049, 35146.82426587851, 34398.65601343955, 34630.30739385148, 34686.0, 34974.30051398907, 35035.0, 38362.0, 38524.80737596294, 38701.41947870728, 38721.80639096787, 38823.611763882, 32758.836205818818, 33136.0, 33144.0, 33693.15767105744, 33705.494417583264, 23824.21010012138, 24017.639735403634, 24089.75910240945, 24282.392940176636, 24360.634590480804], "value": 17400.214794939988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.4185639929268, 599.4215581963405, 600.9248843894513, 605.7213681706412, 610.2036841921293, 22022.229221977235, 23092.76907230927, 23276.88361558192, 23304.18435354763, 23450.64824027637, 26345.0, 26813.463730725372, 26885.731142688568, 26919.0, 26969.595456068175, 6577.901331480043, 6733.0, 6733.764318248862, 6740.595564266159, 6742.190937087556, 4104.507459104897, 6064.787732429366, 6682.866342673144, 6730.461563074967, 6757.7972660820005, 4088.121053973395, 6602.240742314647, 6653.035309880064, 6657.167854018247, 6664.866702665944, 6635.3032931541975, 6660.333966603349, 6661.666916654148, 6671.766488172891, 6687.364700353459, 6160.322364539897, 6170.938290617126, 6171.567990240685, 6172.814815555541, 6185.226846644169, 28991.275218119717, 29785.70214297851, 30313.545296820565, 30388.240293992607, 30832.53751193734, 1885.9905700471547, 1959.1086055844557, 1959.8726082804571, 1961.2155137944935, 1966.5771859050374, 19355.225790968343, 19729.901350493157, 19788.109535070813, 19874.0, 19898.900505497524, 2399.0883464283634, 2407.3500154958238, 2421.576224160765, 2456.2999545129733, 2515.861627610481, 130178.0, 133803.0, 133958.0, 133989.33005335007, 134027.0, 14009.789853152211, 14917.40330386794, 15120.243987800566, 15184.392624294904, 15573.454929077428, 23679.28962131139, 23759.1684291049, 23772.52454950891, 23800.40498987539, 23852.403689907544, 10522.0, 10647.14822814173, 10731.141508679442, 10753.623623173151, 10914.399708016135, 8709.216170544682, 9832.31173817826, 9878.160356369644, 9951.601935922627, 10005.79988400228, 8136.796580085545, 9429.104235097655, 9548.283878709124, 9612.66355677548, 9672.419654820733, 2831.5186418308936, 2844.971550284512, 2910.88356465739, 2918.1683220282116, 3002.564628128953, 955.5652178259014, 975.3562648651856, 975.7024107647094, 982.2485798364257, 987.6790043235977, 19580.216791328472, 19789.91155486433, 19896.60206795856, 20064.297749578986, 20077.0, 1032.8450732390202, 1036.0934182590233, 1037.8391349340714, 1039.308859608352, 1052.3738375666467, 11089.83365249522, 11238.66284011481, 11330.320180789178, 11342.546298147981, 11435.828462573068, 453745.0, 454809.0, 459171.0, 468160.0, 470131.0, 105993.0, 106475.46762266294, 107379.0, 109180.0, 109325.45337273422, 1170.4966864248286, 1178.6876477733408, 1212.8180772883961, 1213.1568559850818, 1213.7633161533415, 17487.737683935007, 17534.649307014042, 17577.736333955258, 17963.371282005068, 18001.63996720032, 51007.0, 52768.73615631897, 52991.0, 53598.46401536091, 54415.0, 67609.0, 68542.31457685359, 68706.65646671735, 75774.0, 79329.0, 14798.63003424923, 15217.6195595111, 15420.074795512304, 15420.768688469458, 15451.0, 23824.0, 23896.40258993539, 24016.51966960616, 24184.637230441207, 24312.756872431757, 18362.449126526226, 18661.346852860086, 18861.71707424417, 19046.0, 19068.141933613297, 16441.342346306013, 16902.915485422494, 17189.226484807965, 17248.0, 17532.03573803453, 18831.529211769815, 19979.20083196656, 20013.0, 20122.698159527325, 20157.899210503852, 14303.570892873231, 15017.399304027716, 15087.698246034799, 15187.544373668808, 15278.694426111639, 18786.812131878505, 18791.90604046971, 18936.0, 18956.336528221447, 19191.80808191956], "value": 12066.240192105723, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.1937566523584, 1420.3892326299688, 1430.6494908747359, 1432.312490004798, 1437.6334034821118, 13659.590212293622, 13922.095063820845, 14276.643083922894, 14307.070040447363, 14308.356123974407, 14476.348564314605, 14743.115413075197, 14747.115173089622, 14759.631009224775, 14782.482613108543, 2621.0826210826203, 2667.186508115024, 2685.8254213476157, 2686.543287641096, 2696.12375977807, 2574.0733335999025, 2682.8122031457806, 2733.521633714102, 2735.7948153888437, 2747.4505098980185, 2650.8409495430237, 2656.3890305229806, 2682.678078630567, 2692.7576518113365, 2701.7433343832404, 2509.3224829296078, 2527.9241622751347, 2545.910893118732, 2549.4391233928504, 2549.643049972996, 2538.3780973661387, 2557.974420255811, 2573.623111635266, 2579.858107804089, 2587.430765231646, 13479.797803032865, 13658.180509169482, 13658.658533536642, 13728.794068088986, 13825.51610693631, 7621.3521850642765, 7852.3325517331095, 7911.327537159348, 7937.603119843985, 7965.7610271691365, 5089.262057001751, 5174.068667639824, 5212.9739351303, 5288.524032837063, 5296.735163241861, 1784.4825000749768, 1792.9551761205944, 1803.0083454100325, 1855.8608104392224, 1856.1554492705786, 22146.557068858692, 22376.440588985242, 22412.32763017112, 22416.66375004376, 22455.775442245696, 6313.9368606314265, 6551.44312733423, 6592.0, 6698.8995165072565, 6911.101556797584, 15843.287052082684, 15853.365865365487, 15870.603234918988, 15878.761818572731, 15887.443939462064, 7196.4962452627815, 7507.0, 7510.136334321623, 7511.8497630047095, 7512.47412681118, 5825.766969321265, 6166.290876549165, 6399.424051835357, 6405.455536279375, 7003.8599228015155, 5086.18621020635, 5233.2468623010955, 5255.132903071027, 5305.495977882095, 5313.1764576490705, 1458.117838707588, 1492.604459818149, 1527.488291422386, 1534.0642208252957, 1552.5885640305328, 920.5259291464901, 937.5780898595523, 939.248601119102, 950.083169741203, 952.6522819170953, 7639.770806875803, 7665.846683066307, 7749.108852482037, 7751.302382785577, 7792.532448053022, 684.6576711644178, 693.1855070292386, 703.409136325489, 710.2435905760349, 716.5843810589845, 4789.209780386267, 5065.214891691794, 5500.944990550123, 5561.555075593943, 6032.336442991267, 18501.259949602136, 19190.80809191918, 19374.70937935904, 19459.51351216231, 19852.0, 18564.350247741266, 18692.252309907723, 19141.330053448062, 19464.318748844005, 19655.50861228452, 10018.499075046218, 10494.68515944523, 10558.683239502981, 10606.681799546026, 10795.83806242907, 8119.675212991414, 8200.138985406686, 8240.21717936795, 8490.872636910575, 8619.1811777881, 17045.14774261257, 17070.914645426692, 17482.213300401258, 17545.385911493035, 17819.376321828673, 17077.231524581177, 17449.738253926454, 17515.0, 17521.912390437967, 18165.27338906429, 11355.772884542428, 11577.07383409342, 11624.0, 11638.941805291259, 11640.243384180001, 12229.816552751894, 12498.812517812059, 12545.372731363577, 12572.308523030955, 12579.308138052129, 10375.948120259653, 10431.269811113149, 10529.157667386438, 10801.351918884891, 10822.729431764268, 10391.84412233802, 10480.737981550523, 10493.317934334244, 10689.30519516229, 10702.09032232269, 9313.39462934907, 9449.43303401798, 9493.0, 9498.762530936781, 9504.42973421597, 7009.439244860297, 7137.427525251204, 7155.499115062104, 7387.150477694919, 7505.324520793155, 7457.2542745724995, 7502.774916752506, 7536.171021187555, 7540.811479713051, 7592.240775922363], "value": 6338.724945572766, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.59197458084384, 501.0055040744122, 528.6193940362939, 529.1480716047166, 532.1087178975215, 6220.564560480768, 6318.431341179299, 6318.5892916960365, 6332.525060620449, 6366.331535188808, 5977.342492325841, 6040.8489787755325, 6138.355472675372, 6163.969180154104, 6167.352427995063, 4977.651564390494, 4979.576735977446, 5018.096742586333, 5030.144875371196, 5064.493550644925, 4900.240462728283, 4911.582515486188, 4967.93830560317, 5016.322796422491, 5027.597792176617, 4809.615230781529, 4902.901941961177, 4944.134776414131, 4955.0, 4960.156773348539, 4917.827876024322, 4945.554900058976, 4957.231629097478, 4962.5533701967, 4990.550850423461, 4409.4047303613925, 4410.669199810027, 4423.712458690175, 4450.666200034978, 4464.1741277863375, 6041.486473649745, 6075.635461872258, 6092.5430592705725, 6114.296855861544, 6173.69131543421, 5706.201131841545, 5729.5702822288495, 5752.453516915928, 5764.452377024176, 5794.797182098607, 6452.419282264619, 6492.156019717454, 6551.868962620769, 6571.901421478682, 6585.934140658628, 4809.278608208761, 4869.951300486985, 4870.0, 4885.682430641997, 4906.0, 6921.792346229621, 6973.442124630017, 7046.224915259318, 7053.589282143541, 7084.43324534036, 4964.379452568429, 4993.675411098267, 5003.224500202475, 5040.974795126074, 5047.318611987408, 5038.521340472649, 5117.488251174853, 5130.640855140105, 5147.485251474897, 5153.484651534816, 5717.285339332583, 5729.713514324267, 5743.54051675865, 5767.279090113736, 5801.477866991991, 6588.439982601532, 6646.60120392778, 6680.164979377578, 6717.798466046026, 6727.495437842182, 1696.0925904640958, 1714.659489796419, 1723.9741403878952, 1749.5538637647574, 1766.752654628353, 1212.1696637803134, 1213.0598785940967, 1216.8174773784003, 1230.2249582762981, 1232.8150777383285, 5671.347795003545, 5719.0, 5760.481556659921, 5762.9914764915975, 5842.795502157404, 6559.934400656028, 6582.76960306387, 6700.597964122168, 6709.832254193684, 6789.388954994074, 5775.364709881909, 5801.593888427769, 5806.738696758656, 5834.329052159056, 5845.532357411397, 3570.517980072657, 3623.1159796905463, 3650.4341827016856, 3652.7443078984215, 3662.9633703663153, 6474.838129046717, 6579.276279609239, 6580.2103747549645, 6602.933970660328, 6605.735770569219, 6943.409810166091, 6961.129858767654, 7036.381632224617, 7047.330503602149, 7054.2240353561065, 6490.740370385226, 6493.123428337209, 6503.2521260054755, 6567.671616419065, 6592.143021407186, 6717.413795688597, 6742.426893713893, 6746.898796518154, 6748.392644662004, 6814.977753336841, 6671.966640166768, 6741.898871516833, 6785.830354241183, 6793.671101160495, 6846.7945961621235, 6516.837079073061, 6532.346765323429, 6534.16471810866, 6574.506911981525, 6609.504287178384, 4976.552110310089, 5006.799728010838, 5021.819872330073, 5031.119554077948, 5034.295198672262, 6864.553804002724, 6868.62222577763, 6926.480513961574, 6937.618430986344, 6955.478339124687, 6566.606003639797, 6582.210134783857, 6620.3710647488415, 6623.503237257129, 6635.777014700129, 6444.742210311535, 6495.76580449713, 6557.868842623026, 6610.272869984202, 6631.303713110054, 6141.84645383869, 6295.18162638854, 6396.552241343062, 6420.775272865428, 6462.450691691259, 6552.410283074546, 6557.639329836905, 6571.605703657605, 6626.801195964129, 6628.867422651617, 6305.148804911279, 6332.493400528039, 6335.081413195153, 6346.492280617447, 6403.583767055126], "value": 5077.17930802666, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.1726978038171, 788.259036505685, 814.8207394373239, 903.268352634366, 929.9302552308576, 18399.72400413993, 19183.71224431631, 19268.99999999998, 19269.71095433568, 19272.3254686086, 20628.587428251427, 21369.57260854782, 21542.030608622612, 21566.568668626616, 21716.34850954474, 961.8268711631921, 1006.9194464442863, 1009.6314845081566, 1015.5734591471563, 1015.7562185075566, 973.8198433289864, 988.461288597716, 990.9851352229722, 998.32612986234, 1001.3841487485217, 1053.3521884041297, 1057.973550661232, 1058.8941105889426, 1059.2479339668805, 1059.6503153959213, 1062.1502797761798, 1062.6387028410338, 1064.0795711709393, 1064.2656566968806, 1064.6805958212583, 776.456480463672, 788.3811208201535, 788.4835432791531, 788.5071830106184, 788.8067423481284, 18089.819101809077, 18798.154083066296, 19691.40925772229, 20094.89952550228, 20155.0930208141, 20252.189912403486, 20392.694109588367, 20651.58696826055, 20685.069171887302, 21214.151433942625, 16382.344706211738, 16606.916965415097, 16711.66576668472, 16864.494065178063, 16875.409360672442, 3596.4425514045365, 3644.671979521856, 3659.0852286928266, 3665.17533554948, 3675.8345874435713, 110636.44681776539, 113035.43482282618, 117467.412662937, 125446.0, 126955.0, 16517.421890234025, 17637.0, 17680.381186658404, 17682.469525914243, 17689.557761056076, 31496.685033149377, 31695.0, 32401.675983239867, 32488.675113248566, 32489.51265731016, 8466.32269418445, 8585.31317494599, 8649.827003460021, 8665.566721664018, 8668.653253869901, 4290.613844753924, 4292.527821939584, 4317.935230971538, 4355.107203023373, 4395.450568678915, 5240.895182096337, 5287.41838397776, 5327.280817089721, 5344.43883392246, 5373.3551973762615, 1072.5334479501441, 1099.0712847643729, 1100.5927806711568, 1108.9852784702068, 1109.9556017759198, 573.7518424358736, 694.2571448550104, 716.3445447415661, 720.6216736213426, 737.1449119021909, 12139.210951288138, 12590.37048147589, 12692.301923394301, 12836.871631283755, 13341.19952802835, 683.8427161752725, 696.7422053840011, 697.7522979342361, 705.8376573388149, 712.9465290103263, 5408.1887716842, 5577.860553486195, 5651.321841378978, 5786.392428795, 5844.298684157929, 106874.46562767136, 109848.0, 110994.0, 112613.0, 116184.0, 89596.5520172408, 92935.0, 97813.0, 98168.0, 99160.0, 80393.0, 82128.58935705402, 82646.76029859557, 82748.58625706832, 83978.58010709907, 11851.466683999413, 11995.82006269924, 12027.4587643558, 12107.75784484323, 12430.378481076092, 33127.503087453224, 34228.315433691685, 34650.96047118591, 36383.636163638024, 37105.81447092747, 63432.0, 64843.0, 65103.67448162729, 66311.33688663051, 69136.65431672809, 18710.345137920107, 19078.141483633546, 19218.135183916478, 19274.32539861098, 19633.70549441788, 19189.712154317418, 19204.903975480032, 19286.61426771485, 19409.70885436748, 19489.317873874345, 19070.809291906902, 19237.903810480857, 19430.417087487396, 19472.123754430802, 19766.40700778979, 16782.832171678616, 16817.41139060127, 16889.91555042217, 17066.31734730597, 17126.657466850345, 18309.725354119964, 18693.532661683566, 19298.80701193026, 19434.514137146685, 19454.51363715862, 9337.0, 9443.0, 9546.665866694633, 9547.0, 9547.283953703361, 14152.43390264378, 14320.856791432368, 14397.087063870282, 14595.416183352547, 14912.701745965236], "value": 9062.021778361193, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 103.0, 107.0, 110.0, 111.0, 107.0, 100.0, 109.0, 110.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 156.0, 179.0, 122.0, 151.0, 144.0, 123.0, 176.0, 129.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 161.0, 164.0, 161.0, 162.0, 171.0, 166.0, 163.0, 162.0, 166.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 174.0, 75.0, 79.0, 81.0, 86.0, 148.0, 80.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 131.0, 78.0, 78.0, 79.0, 129.0, 85.0, 79.0, 139.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 116.0, 114.0, 114.0, 115.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 155.0, 158.0, 152.0, 150.0, 150.0, 151.0, 150.0, 150.0, 150.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 99.0, 98.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 54.0, 54.0, 55.0, 54.0, 54.0, 58.0, 54.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 53.0, 65.0, 54.0, 53.0, 55.0, 53.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 88.0, 81.0, 78.0, 78.0, 78.0, 80.0, 86.0, 88.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 138.0, 137.0, 147.0, 126.0, 142.0, 136.0, 141.0, 137.0, 176.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 180.0, 180.0, 182.0, 198.0, 175.0, 180.0, 183.0, 177.0, 185.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 78.0, 68.0, 66.0, 64.0, 67.0, 67.0, 68.0, 67.0], "value": 68.3, "unit": "score"}]}]} 05:24:54 INFO - Return code: 0 05:24:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:24:54 INFO - # TBPL SUCCESS # 05:24:54 INFO - Running post-action listener: _package_coverage_data 05:24:54 INFO - Running post-action listener: _resource_record_post_action 05:24:54 INFO - [mozharness: 2017-08-08 12:24:54.255743Z] Finished run-tests step (success) 05:24:54 INFO - Running post-run listener: _resource_record_post_run 05:24:54 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 431337472; Read time: 12; Write time: 304684 05:24:54 INFO - TinderboxPrint: CPU usage
13.2% 05:24:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:24:54 INFO - TinderboxPrint: I/O write bytes / time
431,337,472 / 304,684 05:24:54 INFO - TinderboxPrint: CPU idle
8,278.2 (86.8%) 05:24:54 INFO - TinderboxPrint: CPU user
1,206.5 (12.7%) 05:24:54 INFO - TinderboxPrint: Swap in / out
0 / 0 05:24:54 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 107524096; Read time: 0; Write time: 82796 05:24:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:24:54 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 323813376; Read time: 0; Write time: 221888 05:24:54 INFO - Running post-run listener: _upload_blobber_files 05:24:54 INFO - Blob upload gear active. 05:24:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:24:54 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 05:24:54 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'] 05:24:54 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 05:24:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:24:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:24:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:24:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:56 INFO - (blobuploader) - INFO - Done attempting. 05:24:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:24:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:24:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:57 INFO - (blobuploader) - INFO - Done attempting. 05:24:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:24:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:03 INFO - (blobuploader) - INFO - Done attempting. 05:25:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:04 INFO - (blobuploader) - INFO - Done attempting. 05:25:04 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:04 INFO - Return code: 0 05:25:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f72a98d11b3ecf9d6da819a8bcfe81a3ae97c3bb2884fe4048e201f1ee056ee1e94f46b93ae407ce0f8836e538cd66906e4334377c2e3aed95dd65aecaaef986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ef27619928e9bc0ec1fe87fb9772033d7632f143d6722cdf421c8bf5abd107c0c06718f28c89725c0aab21fc54a7434a83fc5d8035e84116a472103878d2a56", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6afe84ec9ed1891df5dcaf97be25c7009eace9955428660d206b5ef314224cd185fed23b6a92263821ab3fdb57a2f84c5f49c66a918ab3fc4d2aca068b2d4a48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15eb183b4a79062ae19d9e58a8e6d46957147ffdb16fff35f1373861e4d10afde106be6df3832bfe4f5afed31d0e5bbaaf101d8d2b1cc8d8f141402e20d7aceb"} 05:25:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:04 INFO - Contents: 05:25:04 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f72a98d11b3ecf9d6da819a8bcfe81a3ae97c3bb2884fe4048e201f1ee056ee1e94f46b93ae407ce0f8836e538cd66906e4334377c2e3aed95dd65aecaaef986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ef27619928e9bc0ec1fe87fb9772033d7632f143d6722cdf421c8bf5abd107c0c06718f28c89725c0aab21fc54a7434a83fc5d8035e84116a472103878d2a56", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6afe84ec9ed1891df5dcaf97be25c7009eace9955428660d206b5ef314224cd185fed23b6a92263821ab3fdb57a2f84c5f49c66a918ab3fc4d2aca068b2d4a48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15eb183b4a79062ae19d9e58a8e6d46957147ffdb16fff35f1373861e4d10afde106be6df3832bfe4f5afed31d0e5bbaaf101d8d2b1cc8d8f141402e20d7aceb"} 05:25:04 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:04 INFO - Copying logs to upload dir... 05:25:04 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.621393 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-08 05:25:04.546620) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:04.549289) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225 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/f72a98d11b3ecf9d6da819a8bcfe81a3ae97c3bb2884fe4048e201f1ee056ee1e94f46b93ae407ce0f8836e538cd66906e4334377c2e3aed95dd65aecaaef986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ef27619928e9bc0ec1fe87fb9772033d7632f143d6722cdf421c8bf5abd107c0c06718f28c89725c0aab21fc54a7434a83fc5d8035e84116a472103878d2a56", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6afe84ec9ed1891df5dcaf97be25c7009eace9955428660d206b5ef314224cd185fed23b6a92263821ab3fdb57a2f84c5f49c66a918ab3fc4d2aca068b2d4a48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15eb183b4a79062ae19d9e58a8e6d46957147ffdb16fff35f1373861e4d10afde106be6df3832bfe4f5afed31d0e5bbaaf101d8d2b1cc8d8f141402e20d7aceb"} build_url:https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f72a98d11b3ecf9d6da819a8bcfe81a3ae97c3bb2884fe4048e201f1ee056ee1e94f46b93ae407ce0f8836e538cd66906e4334377c2e3aed95dd65aecaaef986", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ef27619928e9bc0ec1fe87fb9772033d7632f143d6722cdf421c8bf5abd107c0c06718f28c89725c0aab21fc54a7434a83fc5d8035e84116a472103878d2a56", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6afe84ec9ed1891df5dcaf97be25c7009eace9955428660d206b5ef314224cd185fed23b6a92263821ab3fdb57a2f84c5f49c66a918ab3fc4d2aca068b2d4a48", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15eb183b4a79062ae19d9e58a8e6d46957147ffdb16fff35f1373861e4d10afde106be6df3832bfe4f5afed31d0e5bbaaf101d8d2b1cc8d8f141402e20d7aceb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:04.576788) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:04.577118) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1502188771.959832-1741383225 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:04.656775) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:25:04.660098) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:25:04.660387) ========= ========= Total master_lag: 0.29 =========