builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1501199720.43 results: success (0) revision: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.433997) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.434341) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.434609) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.470741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.471005) ========= 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=1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-27 16:55:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 24.0M=0s 2017-07-27 16:55:21 (24.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151219 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.638089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.638367) ========= 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=1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017729 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:20.688865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-07-27 16:55:20.689191) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c --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 a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c --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=1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-27 16:55:21,902 truncating revision to first 12 chars 2017-07-27 16:55:21,902 Setting DEBUG logging. 2017-07-27 16:55:21,902 attempt 1/10 2017-07-27 16:55:21,902 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a4afa89bfdd1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-27 16:55:23,109 attempt 1/10 2017-07-27 16:55:23,639 current task status: no status available at this point. state: STARTED 2017-07-27 16:55:23,639 sleeping for 10.00s (attempt 1/10) 2017-07-27 16:55:33,650 attempt 2/10 2017-07-27 16:55:33,960 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-07-27 16:55:34,166 unpacking tar archive at: mozilla-central-a4afa89bfdd1/testing/mozharness/ program finished with exit code 0 elapsedTime=12.602019 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-07-27 16:55:33.304048) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:33.304325) ========= script_repo_revision: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:33.304680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:33.304967) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 16:55:33.314233) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-07-27 16:55:33.314496) ========= /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=1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:55:34 INFO - MultiFileLogger online at 20170727 16:55:34 in /builds/slave/test 16:55:34 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 16:55:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:55:34 INFO - {'append_to_log': False, 16:55:34 INFO - 'base_work_dir': '/builds/slave/test', 16:55:34 INFO - 'blob_upload_branch': 'Firefox', 16:55:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:55:34 INFO - 'branch': 'Firefox', 16:55:34 INFO - 'buildbot_json_path': 'buildprops.json', 16:55:34 INFO - 'code_coverage': False, 16:55:34 INFO - 'config_files': ('talos/linux_config.py',), 16:55:34 INFO - 'default_actions': ('clobber', 16:55:34 INFO - 'read-buildbot-config', 16:55:34 INFO - 'download-and-extract', 16:55:34 INFO - 'populate-webroot', 16:55:34 INFO - 'create-virtualenv', 16:55:34 INFO - 'install', 16:55:34 INFO - 'setup-mitmproxy', 16:55:34 INFO - 'run-tests'), 16:55:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:55:34 INFO - 'disable_ccov_upload': False, 16:55:34 INFO - 'download_minidump_stackwalk': True, 16:55:34 INFO - 'download_symbols': 'ondemand', 16:55:34 INFO - 'enable_stylo': False, 16:55:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:55:34 INFO - 'tooltool.py': '/tools/tooltool.py', 16:55:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:55:34 INFO - '/tools/misc-python/virtualenv.py')}, 16:55:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:55:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:55:34 INFO - 'gecko_profile': False, 16:55:34 INFO - 'gecko_profile_interval': 0, 16:55:34 INFO - 'installer_path': 'installer.exe', 16:55:34 INFO - 'log_level': 'info', 16:55:34 INFO - 'log_name': 'talos', 16:55:34 INFO - 'log_to_console': True, 16:55:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:55:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:55:34 INFO - 'opt_config_files': (), 16:55:34 INFO - 'pip_index': False, 16:55:34 INFO - 'suite': 'dromaeojs-e10s', 16:55:34 INFO - 'system_bits': '32', 16:55:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:55:34 INFO - 'title': 'talos-linux64-ix-079', 16:55:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:55:34 INFO - 'use_talos_json': True, 16:55:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:55:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:55:34 INFO - 'work_dir': 'build'} 16:55:34 INFO - [mozharness: 2017-07-27 23:55:34.635655Z] Running clobber step. 16:55:34 INFO - Running pre-action listener: _resource_record_pre_action 16:55:34 INFO - Running main action method: clobber 16:55:34 INFO - rmtree: /builds/slave/test/build 16:55:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:55:35 INFO - Running post-action listener: _resource_record_post_action 16:55:35 INFO - [mozharness: 2017-07-27 23:55:35.680057Z] Finished clobber step (success) 16:55:35 INFO - [mozharness: 2017-07-27 23:55:35.680159Z] Running read-buildbot-config step. 16:55:35 INFO - Running pre-action listener: _resource_record_pre_action 16:55:35 INFO - Running main action method: read_buildbot_config 16:55:35 INFO - Using buildbot properties: 16:55:35 INFO - { 16:55:35 INFO - "project": "", 16:55:35 INFO - "product": "firefox", 16:55:35 INFO - "who": "kwierso@gmail.com", 16:55:35 INFO - "installer_path": "public/build/target.tar.bz2", 16:55:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 16:55:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:55:35 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 16:55:35 INFO - "stage_platform": "linux64-stylo", 16:55:35 INFO - "basedir": "/builds/slave/test", 16:55:35 INFO - "buildnumber": 241, 16:55:35 INFO - "platform": "ubuntu64_hw_stylo", 16:55:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:55:35 INFO - "slavebuilddir": "test", 16:55:35 INFO - "taskId": "UYS3Y3lVSZqpQnwGACZEdg", 16:55:35 INFO - "branch": "mozilla-central", 16:55:35 INFO - "script_repo_revision": "production", 16:55:35 INFO - "revision": "a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c", 16:55:35 INFO - "slavename": "talos-linux64-ix-079", 16:55:35 INFO - "repo_path": "mozilla-central" 16:55:35 INFO - } 16:55:35 INFO - Finding installer, test and symbols from parent task. 16:55:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UYS3Y3lVSZqpQnwGACZEdg'}, attempt #1 16:55:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UYS3Y3lVSZqpQnwGACZEdg'}, attempt #1 16:55:43 INFO - Task dependencies: M4-l_DlIR0urDflX_RShmA 16:55:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA'}, attempt #1 16:55:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:55:44 INFO - Set test_url: https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/test_packages.json 16:55:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip 16:55:44 INFO - Running post-action listener: _resource_record_post_action 16:55:44 INFO - [mozharness: 2017-07-27 23:55:44.014311Z] Finished read-buildbot-config step (success) 16:55:44 INFO - [mozharness: 2017-07-27 23:55:44.014537Z] Running download-and-extract step. 16:55:44 INFO - Running pre-action listener: _resource_record_pre_action 16:55:44 INFO - Running main action method: download_and_extract 16:55:44 INFO - mkdir: /builds/slave/test/build/tests 16:55: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')]} 16:55:44 INFO - trying https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.test_packages.json 16:55:44 INFO - Downloading https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:55:44 INFO - Downloaded 989 bytes. 16:55:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:55:44 INFO - Using the following test package requirements: 16:55:44 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:55:44 INFO - u'common': [u'target.common.tests.zip'], 16:55:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:55:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:55:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:55:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:55:44 INFO - u'mozbase': [u'target.common.tests.zip'], 16:55:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:55:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:55:44 INFO - u'web-platform': [u'target.common.tests.zip', 16:55:44 INFO - u'target.web-platform.tests.tar.gz'], 16:55:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:55:44 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:55:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip 16:55:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:55:44 INFO - Fetch https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip into memory 16:55:45 INFO - Content-Length response header: 72561184 16:55:45 INFO - Bytes received: 72561184 16:55:56 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:55:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip 16:55:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:55:56 INFO - Fetch https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.common.tests.zip into memory 16:55:58 INFO - Content-Length response header: 72561184 16:55:58 INFO - Bytes received: 72561184 16:56:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.talos.tests.zip 16:56:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:56:10 INFO - Fetch https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.talos.tests.zip into memory 16:56:11 INFO - Content-Length response header: 14496465 16:56:11 INFO - Bytes received: 14496465 16:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:11 INFO - trying https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:56:11 INFO - Downloading https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:56:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:56:12 INFO - Downloaded 62184176 bytes. 16:56:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:56:12 INFO - mkdir: /builds/slave/test/properties 16:56:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:56:12 INFO - Writing to file /builds/slave/test/properties/build_url 16:56:12 INFO - Contents: 16:56:12 INFO - build_url:https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 16:56:12 INFO - Running post-action listener: _resource_record_post_action 16:56:12 INFO - Running post-action listener: set_extra_try_arguments 16:56:12 INFO - [mozharness: 2017-07-27 23:56:12.629780Z] Finished download-and-extract step (success) 16:56:12 INFO - [mozharness: 2017-07-27 23:56:12.629876Z] Running populate-webroot step. 16:56:12 INFO - Running pre-action listener: _resource_record_pre_action 16:56:12 INFO - Running main action method: populate_webroot 16:56:12 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:56:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:56:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:56:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:56:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:56:12 INFO - 'tests': ['damp', 'tps']}, 16:56:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:56:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:56:12 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:56:12 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:56:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:56:12 INFO - 'ts_paint', 16:56:12 INFO - 'tpaint', 16:56:12 INFO - 'sessionrestore', 16:56:12 INFO - 'sessionrestore_many_windows', 16:56:12 INFO - 'sessionrestore_no_auto_restore', 16:56:12 INFO - 'tabpaint']}, 16:56:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:56:12 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:56:12 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:56:12 INFO - 'talos_options': ['--mitmproxy', 16:56:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:56:12 INFO - '--firstNonBlankPaint'], 16:56:12 INFO - 'tests': ['quantum_pageload_google', 16:56:12 INFO - 'quantum_pageload_youtube', 16:56:12 INFO - 'quantum_pageload_amazon', 16:56:12 INFO - 'quantum_pageload_facebook']}, 16:56:12 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:56:12 INFO - 'talos_options': ['--stylo', 16:56:12 INFO - '--mitmproxy', 16:56:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:56:12 INFO - '--firstNonBlankPaint'], 16:56:12 INFO - 'tests': ['quantum_pageload_google', 16:56:12 INFO - 'quantum_pageload_youtube', 16:56:12 INFO - 'quantum_pageload_amazon', 16:56:12 INFO - 'quantum_pageload_facebook']}, 16:56:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:56:12 INFO - 'tsvgr_opacity', 16:56:12 INFO - 'tart', 16:56:12 INFO - 'tscrollx', 16:56:12 INFO - 'cart', 16:56:12 INFO - 'tsvg_static']}, 16:56:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:56:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:56:12 INFO - 'talos_options': ['--xperf_path', 16:56:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:56:12 INFO - 'tests': ['tp5n']}}} 16:56:12 INFO - Running post-action listener: _resource_record_post_action 16:56:12 INFO - [mozharness: 2017-07-27 23:56:12.637197Z] Finished populate-webroot step (success) 16:56:12 INFO - [mozharness: 2017-07-27 23:56:12.637287Z] Running create-virtualenv step. 16:56:12 INFO - Running pre-action listener: _resource_record_pre_action 16:56:12 INFO - Running main action method: create_virtualenv 16:56:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:56:12 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 16:56:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:56:12 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:56:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:56:12 INFO - Using real prefix '/usr' 16:56:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:56:13 INFO - Installing distribute.............................................................................................................................................................................................done. 16:56:15 INFO - Installing pip.................done. 16:56:15 INFO - Return code: 0 16:56:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:56:15 INFO - Reading from file tmpfile_stdout 16:56:15 INFO - Output received: 16:56:15 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) 16:56:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:56: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')]} 16:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56: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')]} 16:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:15 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:15 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 16:56:15 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 16:56:15 INFO - Using env: {'DISPLAY': ':0', 16:56:15 INFO - 'HOME': '/home/cltbld', 16:56:15 INFO - 'LANG': 'en_US.UTF-8', 16:56:15 INFO - 'LANGUAGE': 'en_US:en', 16:56:15 INFO - 'LOGNAME': 'cltbld', 16:56:15 INFO - 'MAIL': '/var/mail/cltbld', 16:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:15 INFO - 'MOZ_NO_REMOTE': '1', 16:56:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:15 INFO - 'NO_EM_RESTART': '1', 16:56:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:15 INFO - 'PWD': '/builds/slave/test', 16:56:15 INFO - 'SHELL': '/bin/bash', 16:56:15 INFO - 'SHLVL': '1', 16:56:15 INFO - 'TERM': 'linux', 16:56:15 INFO - 'TMOUT': '86400', 16:56:15 INFO - 'USER': 'cltbld', 16:56:15 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:15 INFO - '_': '/tools/buildbot/bin/python'} 16:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:15 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 16:56:15 INFO - Cleaning up... 16:56:15 INFO - Return code: 0 16:56:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:56: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')]} 16:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56: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')]} 16:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:15 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:15 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 16:56:15 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 16:56:15 INFO - Using env: {'DISPLAY': ':0', 16:56:15 INFO - 'HOME': '/home/cltbld', 16:56:15 INFO - 'LANG': 'en_US.UTF-8', 16:56:15 INFO - 'LANGUAGE': 'en_US:en', 16:56:15 INFO - 'LOGNAME': 'cltbld', 16:56:15 INFO - 'MAIL': '/var/mail/cltbld', 16:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:15 INFO - 'MOZ_NO_REMOTE': '1', 16:56:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:15 INFO - 'NO_EM_RESTART': '1', 16:56:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:15 INFO - 'PWD': '/builds/slave/test', 16:56:15 INFO - 'SHELL': '/bin/bash', 16:56:15 INFO - 'SHLVL': '1', 16:56:15 INFO - 'TERM': 'linux', 16:56:15 INFO - 'TMOUT': '86400', 16:56:15 INFO - 'USER': 'cltbld', 16:56:15 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:15 INFO - '_': '/tools/buildbot/bin/python'} 16:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:16 INFO - Downloading/unpacking psutil>=3.1.1 16:56:16 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 16:56:16 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 16:56:16 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 16:56:16 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 16:56:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:56:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:56:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:56:17 INFO - Installing collected packages: psutil 16:56:17 INFO - Running setup.py install for psutil 16:56:17 INFO - building 'psutil._psutil_linux' extension 16:56:17 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 16:56:17 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 16:56:17 INFO - building 'psutil._psutil_posix' extension 16:56:17 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 16:56:17 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 16:56:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:56:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:56:17 INFO - Successfully installed psutil 16:56:17 INFO - Cleaning up... 16:56:17 INFO - Return code: 0 16:56:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:17 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:17 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 16:56:17 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 16:56:17 INFO - Using env: {'DISPLAY': ':0', 16:56:17 INFO - 'HOME': '/home/cltbld', 16:56:17 INFO - 'LANG': 'en_US.UTF-8', 16:56:17 INFO - 'LANGUAGE': 'en_US:en', 16:56:17 INFO - 'LOGNAME': 'cltbld', 16:56:17 INFO - 'MAIL': '/var/mail/cltbld', 16:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:17 INFO - 'MOZ_NO_REMOTE': '1', 16:56:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:17 INFO - 'NO_EM_RESTART': '1', 16:56:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:17 INFO - 'PWD': '/builds/slave/test', 16:56:17 INFO - 'SHELL': '/bin/bash', 16:56:17 INFO - 'SHLVL': '1', 16:56:17 INFO - 'TERM': 'linux', 16:56:17 INFO - 'TMOUT': '86400', 16:56:17 INFO - 'USER': 'cltbld', 16:56:17 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:17 INFO - '_': '/tools/buildbot/bin/python'} 16:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:56:18 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 16:56:18 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 16:56:18 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 16:56:18 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 16:56:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:56:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:56:19 INFO - Installing collected packages: mozsystemmonitor 16:56:19 INFO - Running setup.py install for mozsystemmonitor 16:56:19 INFO - Successfully installed mozsystemmonitor 16:56:19 INFO - Cleaning up... 16:56:19 INFO - Return code: 0 16:56:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:56:19 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')]} 16:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:19 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')]} 16:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:19 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:19 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 16:56:19 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 16:56:19 INFO - Using env: {'DISPLAY': ':0', 16:56:19 INFO - 'HOME': '/home/cltbld', 16:56:19 INFO - 'LANG': 'en_US.UTF-8', 16:56:19 INFO - 'LANGUAGE': 'en_US:en', 16:56:19 INFO - 'LOGNAME': 'cltbld', 16:56:19 INFO - 'MAIL': '/var/mail/cltbld', 16:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:19 INFO - 'MOZ_NO_REMOTE': '1', 16:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:19 INFO - 'NO_EM_RESTART': '1', 16:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:19 INFO - 'PWD': '/builds/slave/test', 16:56:19 INFO - 'SHELL': '/bin/bash', 16:56:19 INFO - 'SHLVL': '1', 16:56:19 INFO - 'TERM': 'linux', 16:56:19 INFO - 'TMOUT': '86400', 16:56:19 INFO - 'USER': 'cltbld', 16:56:19 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:19 INFO - '_': '/tools/buildbot/bin/python'} 16:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:19 INFO - Downloading/unpacking jsonschema==2.5.1 16:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:56:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:56:20 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 16:56:20 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 16:56:20 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 16:56:20 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 16:56:20 INFO - Downloading functools32-3.2.3-2.tar.gz 16:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:56:20 INFO - warning: no files found matching '*.txt' 16:56:20 INFO - no previously-included directories found matching 'build' 16:56:20 INFO - no previously-included directories found matching 'dist' 16:56:20 INFO - no previously-included directories found matching '.git*' 16:56:20 INFO - Installing collected packages: jsonschema, functools32 16:56:20 INFO - Running setup.py install for functools32 16:56:20 INFO - warning: no files found matching '*.txt' 16:56:20 INFO - no previously-included directories found matching 'build' 16:56:20 INFO - no previously-included directories found matching 'dist' 16:56:20 INFO - no previously-included directories found matching '.git*' 16:56:20 INFO - Successfully installed jsonschema functools32 16:56:20 INFO - Cleaning up... 16:56:20 INFO - Return code: 0 16:56:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:20 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:20 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 16:56:20 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 16:56:20 INFO - Using env: {'DISPLAY': ':0', 16:56:20 INFO - 'HOME': '/home/cltbld', 16:56:20 INFO - 'LANG': 'en_US.UTF-8', 16:56:20 INFO - 'LANGUAGE': 'en_US:en', 16:56:20 INFO - 'LOGNAME': 'cltbld', 16:56:20 INFO - 'MAIL': '/var/mail/cltbld', 16:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:20 INFO - 'MOZ_NO_REMOTE': '1', 16:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:20 INFO - 'NO_EM_RESTART': '1', 16:56:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:20 INFO - 'PWD': '/builds/slave/test', 16:56:20 INFO - 'SHELL': '/bin/bash', 16:56:20 INFO - 'SHLVL': '1', 16:56:20 INFO - 'TERM': 'linux', 16:56:20 INFO - 'TMOUT': '86400', 16:56:20 INFO - 'USER': 'cltbld', 16:56:20 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:20 INFO - '_': '/tools/buildbot/bin/python'} 16:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:56:21 INFO - Cleaning up... 16:56:21 INFO - Return code: 0 16:56:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:56:21 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')]} 16:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:21 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')]} 16:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:21 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:21 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 16:56:21 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 16:56:21 INFO - Using env: {'DISPLAY': ':0', 16:56:21 INFO - 'HOME': '/home/cltbld', 16:56:21 INFO - 'LANG': 'en_US.UTF-8', 16:56:21 INFO - 'LANGUAGE': 'en_US:en', 16:56:21 INFO - 'LOGNAME': 'cltbld', 16:56:21 INFO - 'MAIL': '/var/mail/cltbld', 16:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:21 INFO - 'MOZ_NO_REMOTE': '1', 16:56:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:21 INFO - 'NO_EM_RESTART': '1', 16:56:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:21 INFO - 'PWD': '/builds/slave/test', 16:56:21 INFO - 'SHELL': '/bin/bash', 16:56:21 INFO - 'SHLVL': '1', 16:56:21 INFO - 'TERM': 'linux', 16:56:21 INFO - 'TMOUT': '86400', 16:56:21 INFO - 'USER': 'cltbld', 16:56:21 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:21 INFO - '_': '/tools/buildbot/bin/python'} 16:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:21 INFO - Downloading/unpacking blobuploader==1.2.4 16:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:22 INFO - Downloading blobuploader-1.2.4.tar.gz 16:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:56:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:56:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:22 INFO - Downloading docopt-0.6.1.tar.gz 16:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:56:23 INFO - Installing collected packages: blobuploader, requests, docopt 16:56:23 INFO - Running setup.py install for blobuploader 16:56:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:56:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:56:23 INFO - Running setup.py install for requests 16:56:23 INFO - Running setup.py install for docopt 16:56:23 INFO - Successfully installed blobuploader requests docopt 16:56:23 INFO - Cleaning up... 16:56:23 INFO - Return code: 0 16:56:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:23 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')]} 16:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:23 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')]} 16:56:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:23 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:23 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 16:56:23 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 16:56:23 INFO - Using env: {'DISPLAY': ':0', 16:56:23 INFO - 'HOME': '/home/cltbld', 16:56:23 INFO - 'LANG': 'en_US.UTF-8', 16:56:23 INFO - 'LANGUAGE': 'en_US:en', 16:56:23 INFO - 'LOGNAME': 'cltbld', 16:56:23 INFO - 'MAIL': '/var/mail/cltbld', 16:56:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:23 INFO - 'MOZ_NO_REMOTE': '1', 16:56:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:23 INFO - 'NO_EM_RESTART': '1', 16:56:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:23 INFO - 'PWD': '/builds/slave/test', 16:56:23 INFO - 'SHELL': '/bin/bash', 16:56:23 INFO - 'SHLVL': '1', 16:56:23 INFO - 'TERM': 'linux', 16:56:23 INFO - 'TMOUT': '86400', 16:56:23 INFO - 'USER': 'cltbld', 16:56:23 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:23 INFO - '_': '/tools/buildbot/bin/python'} 16:56:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:23 INFO - Running setup.py (path:/tmp/pip-3pWnaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:23 INFO - Running setup.py (path:/tmp/pip-_WcXsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:24 INFO - Running setup.py (path:/tmp/pip-vmU28n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:24 INFO - Running setup.py (path:/tmp/pip-ZjvHM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:24 INFO - Running setup.py (path:/tmp/pip-705lc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:24 INFO - Running setup.py (path:/tmp/pip-pY33qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:24 INFO - Running setup.py (path:/tmp/pip-dgTRE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:24 INFO - Running setup.py (path:/tmp/pip-lf8QlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:24 INFO - Running setup.py (path:/tmp/pip-MCA97M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:24 INFO - Running setup.py (path:/tmp/pip-87UvzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:24 INFO - Running setup.py (path:/tmp/pip-0MQysO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:24 INFO - Running setup.py (path:/tmp/pip-Cv4maU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:24 INFO - Running setup.py (path:/tmp/pip-XbBFNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:24 INFO - Running setup.py (path:/tmp/pip-HaYaN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:24 INFO - Running setup.py (path:/tmp/pip-iIBjTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:24 INFO - Running setup.py (path:/tmp/pip-JAlPab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:25 INFO - Running setup.py (path:/tmp/pip-RH_ogv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:56:25 INFO - Running setup.py install for manifestparser 16:56:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Running setup.py install for mozcrash 16:56:25 INFO - Running setup.py install for mozdebug 16:56:25 INFO - Running setup.py install for mozdevice 16:56:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Running setup.py install for mozfile 16:56:25 INFO - Running setup.py install for mozhttpd 16:56:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Running setup.py install for mozinfo 16:56:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Running setup.py install for mozInstall 16:56:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:56:25 INFO - Running setup.py install for mozleak 16:56:25 INFO - Running setup.py install for mozlog 16:56:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Running setup.py install for moznetwork 16:56:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Running setup.py install for mozprocess 16:56:26 INFO - Running setup.py install for mozprofile 16:56:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Running setup.py install for mozrunner 16:56:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Running setup.py install for mozscreenshot 16:56:26 INFO - Running setup.py install for moztest 16:56:26 INFO - Running setup.py install for mozversion 16:56:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:56:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:56:26 INFO - Cleaning up... 16:56:26 INFO - Return code: 0 16:56:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:26 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:26 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 16:56:26 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 16:56:26 INFO - Using env: {'DISPLAY': ':0', 16:56:26 INFO - 'HOME': '/home/cltbld', 16:56:26 INFO - 'LANG': 'en_US.UTF-8', 16:56:26 INFO - 'LANGUAGE': 'en_US:en', 16:56:26 INFO - 'LOGNAME': 'cltbld', 16:56:26 INFO - 'MAIL': '/var/mail/cltbld', 16:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:26 INFO - 'MOZ_NO_REMOTE': '1', 16:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:26 INFO - 'NO_EM_RESTART': '1', 16:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:26 INFO - 'PWD': '/builds/slave/test', 16:56:26 INFO - 'SHELL': '/bin/bash', 16:56:26 INFO - 'SHLVL': '1', 16:56:26 INFO - 'TERM': 'linux', 16:56:26 INFO - 'TMOUT': '86400', 16:56:26 INFO - 'USER': 'cltbld', 16:56:26 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:26 INFO - '_': '/tools/buildbot/bin/python'} 16:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:27 INFO - Running setup.py (path:/tmp/pip-JOdt96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:27 INFO - Running setup.py (path:/tmp/pip-KSnVD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:27 INFO - Running setup.py (path:/tmp/pip-xWojNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:27 INFO - Running setup.py (path:/tmp/pip-SBFdRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:27 INFO - Running setup.py (path:/tmp/pip-eQtkTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:27 INFO - Running setup.py (path:/tmp/pip-Zzf9ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:27 INFO - Running setup.py (path:/tmp/pip-SVzahn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:27 INFO - Running setup.py (path:/tmp/pip-evkofA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:27 INFO - Running setup.py (path:/tmp/pip-S5to84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:27 INFO - Running setup.py (path:/tmp/pip-DGse3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:27 INFO - Running setup.py (path:/tmp/pip-A2zImk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:27 INFO - Running setup.py (path:/tmp/pip-5N6x8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:27 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)) 16:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:27 INFO - Running setup.py (path:/tmp/pip-LKe88W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:28 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)) 16:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:28 INFO - Running setup.py (path:/tmp/pip-0O9Q9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:28 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)) 16:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:28 INFO - Running setup.py (path:/tmp/pip-ZBqvHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:28 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)) 16:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:28 INFO - Running setup.py (path:/tmp/pip-i4nBWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:28 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)) 16:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:28 INFO - Running setup.py (path:/tmp/pip-QrtUol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:28 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)) 16:56:28 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)) 16:56:28 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)) 16:56:28 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)) 16:56:28 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)) 16:56:28 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)) 16:56:28 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)) 16:56:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:56:28 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 16:56:28 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 16:56:28 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 16:56:28 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 16:56:29 INFO - Downloading blessings-1.6.tar.gz 16:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:56:29 INFO - Installing collected packages: blessings 16:56:29 INFO - Running setup.py install for blessings 16:56:29 INFO - Successfully installed blessings 16:56:29 INFO - Cleaning up... 16:56:29 INFO - Return code: 0 16:56:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:56:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:29 INFO - Reading from file tmpfile_stdout 16:56:29 INFO - Current package versions: 16:56:29 INFO - argparse == 1.2.1 16:56:29 INFO - blessings == 1.6 16:56:29 INFO - blobuploader == 1.2.4 16:56:29 INFO - docopt == 0.6.1 16:56:29 INFO - functools32 == 3.2.3-2 16:56:29 INFO - jsonschema == 2.5.1 16:56:29 INFO - manifestparser == 1.1 16:56:29 INFO - mozInstall == 1.13 16:56:29 INFO - mozcrash == 1.0 16:56:29 INFO - mozdebug == 0.1 16:56:29 INFO - mozdevice == 0.51 16:56:29 INFO - mozfile == 1.2 16:56:29 INFO - mozhttpd == 0.7 16:56:29 INFO - mozinfo == 0.10 16:56:29 INFO - mozleak == 0.1 16:56:29 INFO - mozlog == 3.5 16:56:29 INFO - moznetwork == 0.27 16:56:29 INFO - mozprocess == 0.25 16:56:29 INFO - mozprofile == 0.28 16:56:29 INFO - mozrunner == 6.13 16:56:29 INFO - mozscreenshot == 0.1 16:56:29 INFO - mozsystemmonitor == 0.3 16:56:29 INFO - moztest == 0.8 16:56:29 INFO - mozversion == 1.4 16:56:29 INFO - psutil == 3.1.1 16:56:29 INFO - requests == 1.2.3 16:56:29 INFO - wsgiref == 0.1.2 16:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:29 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')]} 16:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:29 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')]} 16:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:29 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 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:29 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 16:56:29 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 16:56:29 INFO - Using env: {'DISPLAY': ':0', 16:56:29 INFO - 'HOME': '/home/cltbld', 16:56:29 INFO - 'LANG': 'en_US.UTF-8', 16:56:29 INFO - 'LANGUAGE': 'en_US:en', 16:56:29 INFO - 'LOGNAME': 'cltbld', 16:56:29 INFO - 'MAIL': '/var/mail/cltbld', 16:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:29 INFO - 'MOZ_NO_REMOTE': '1', 16:56:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:29 INFO - 'NO_EM_RESTART': '1', 16:56:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:29 INFO - 'PWD': '/builds/slave/test', 16:56:29 INFO - 'SHELL': '/bin/bash', 16:56:29 INFO - 'SHLVL': '1', 16:56:29 INFO - 'TERM': 'linux', 16:56:29 INFO - 'TMOUT': '86400', 16:56:29 INFO - 'USER': 'cltbld', 16:56:29 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:29 INFO - '_': '/tools/buildbot/bin/python'} 16:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 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)) 16:56:30 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:30 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 16:56:30 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 16:56:30 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 16:56:30 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 16:56:31 INFO - Downloading marionette_driver-1.4.0.tar.gz 16:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 16:56:31 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 16:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:56:31 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)) 16:56:31 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)) 16:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:31 INFO - Installing collected packages: marionette-driver, simplejson 16:56:31 INFO - Running setup.py install for marionette-driver 16:56:31 INFO - Running setup.py install for simplejson 16:56:31 INFO - building 'simplejson._speedups' extension 16:56:31 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 16:56:32 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 16:56:32 INFO - Successfully installed marionette-driver simplejson 16:56:32 INFO - Cleaning up... 16:56:32 INFO - Return code: 0 16:56:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23b2d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, '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': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', '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 16:56:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:56:32 INFO - Using env: {'DISPLAY': ':0', 16:56:32 INFO - 'HOME': '/home/cltbld', 16:56:32 INFO - 'LANG': 'en_US.UTF-8', 16:56:32 INFO - 'LANGUAGE': 'en_US:en', 16:56:32 INFO - 'LOGNAME': 'cltbld', 16:56:32 INFO - 'MAIL': '/var/mail/cltbld', 16:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:32 INFO - 'MOZ_NO_REMOTE': '1', 16:56:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:32 INFO - 'NO_EM_RESTART': '1', 16:56:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:32 INFO - 'PWD': '/builds/slave/test', 16:56:32 INFO - 'SHELL': '/bin/bash', 16:56:32 INFO - 'SHLVL': '1', 16:56:32 INFO - 'TERM': 'linux', 16:56:32 INFO - 'TMOUT': '86400', 16:56:32 INFO - 'USER': 'cltbld', 16:56:32 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:32 INFO - '_': '/tools/buildbot/bin/python'} 16:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:56:32 INFO - Cleaning up... 16:56:32 INFO - Return code: 0 16:56:32 INFO - Running post-action listener: _resource_record_post_action 16:56:32 INFO - Running post-action listener: _start_resource_monitoring 16:56:32 INFO - Starting resource monitoring. 16:56:32 INFO - [mozharness: 2017-07-27 23:56:32.875894Z] Finished create-virtualenv step (success) 16:56:32 INFO - [mozharness: 2017-07-27 23:56:32.876406Z] Running install step. 16:56:32 INFO - Running pre-action listener: _resource_record_pre_action 16:56:32 INFO - Running main action method: install 16:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:33 INFO - Reading from file tmpfile_stdout 16:56:33 INFO - Detecting whether we're running mozinstall >=1.0... 16:56:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:56:33 INFO - Reading from file tmpfile_stdout 16:56:33 INFO - Output received: 16:56:33 INFO - Usage: mozinstall [options] installer 16:56:33 INFO - Options: 16:56:33 INFO - -h, --help show this help message and exit 16:56:33 INFO - -d DEST, --destination=DEST 16:56:33 INFO - Directory to install application into. [default: 16:56:33 INFO - "/builds/slave/test"] 16:56:33 INFO - --app=APP Application being installed. [default: firefox] 16:56:33 INFO - mkdir: /builds/slave/test/build/application 16:56:33 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'] 16:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:56:51 INFO - Reading from file tmpfile_stdout 16:56:51 INFO - Output received: 16:56:51 INFO - /builds/slave/test/build/application/firefox/firefox 16:56:51 INFO - Running post-action listener: _resource_record_post_action 16:56:51 INFO - [mozharness: 2017-07-27 23:56:51.221562Z] Finished install step (success) 16:56:51 INFO - [mozharness: 2017-07-27 23:56:51.221826Z] Running setup-mitmproxy step. 16:56:51 INFO - Running pre-action listener: _resource_record_pre_action 16:56:51 INFO - Running main action method: setup_mitmproxy 16:56:51 INFO - Skipping: mitmproxy is not required 16:56:51 INFO - Running post-action listener: _resource_record_post_action 16:56:51 INFO - [mozharness: 2017-07-27 23:56:51.222969Z] Finished setup-mitmproxy step (success) 16:56:51 INFO - [mozharness: 2017-07-27 23:56:51.223191Z] Running run-tests step. 16:56:51 INFO - Running pre-action listener: _resource_record_pre_action 16:56:51 INFO - Running pre-action listener: _set_gcov_prefix 16:56:51 INFO - Running main action method: run_tests 16:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:56:51 INFO - Python 2.7.3 16:56:51 INFO - Return code: 0 16:56:51 INFO - grabbing minidump binary from tooltool 16:56: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')]} 16:56:51 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 0x23afeb0>, '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 0x23b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23b1240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:56:51 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 16:56:51 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 16:56:51 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 16:56:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:56:51 INFO - Return code: 0 16:56:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:56:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:56:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:56:51 INFO - ENV: RUST_BACKTRACE is now 1 16:56:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:56:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:56:51 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/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 16:56:51 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/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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 16:56:51 INFO - Using env: {'DISPLAY': ':0', 16:56:51 INFO - 'HOME': '/home/cltbld', 16:56:51 INFO - 'LANG': 'en_US.UTF-8', 16:56:51 INFO - 'LANGUAGE': 'en_US:en', 16:56:51 INFO - 'LOGNAME': 'cltbld', 16:56:51 INFO - 'MAIL': '/var/mail/cltbld', 16:56:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:56:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:51 INFO - 'MOZ_NO_REMOTE': '1', 16:56:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:51 INFO - 'NO_EM_RESTART': '1', 16:56:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:51 INFO - 'PWD': '/builds/slave/test', 16:56:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:56:51 INFO - 'RUST_BACKTRACE': '1', 16:56:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:56:51 INFO - 'SHELL': '/bin/bash', 16:56:51 INFO - 'SHLVL': '1', 16:56:51 INFO - 'STYLO_FORCE_ENABLED': '1', 16:56:51 INFO - 'TERM': 'linux', 16:56:51 INFO - 'TMOUT': '86400', 16:56:51 INFO - 'USER': 'cltbld', 16:56:51 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777', 16:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:51 INFO - '_': '/tools/buildbot/bin/python'} 16:56:51 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/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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 16:56:51 INFO - mozversion application_buildid: 20170727231007 16:56:51 INFO - mozversion application_changeset: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c 16:56:51 INFO - mozversion application_display_name: Nightly 16:56:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:56:51 INFO - mozversion application_name: Firefox 16:56:51 INFO - mozversion application_remotingname: firefox 16:56:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:56:51 INFO - mozversion application_vendor: Mozilla 16:56:51 INFO - mozversion application_version: 56.0a1 16:56:51 INFO - mozversion platform_buildid: 20170727231007 16:56:51 INFO - mozversion platform_changeset: a4afa89bfdd10a903cfa9aa5a5bd1624dc85548c 16:56:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:56:51 INFO - mozversion platform_version: 56.0a1 16:56:51 INFO - using testdate: 1501199811 16:56:51 INFO - actual date: 1501199811 16:56:51 INFO - starting webserver on 'localhost:54554' 16:56:51 INFO - SUITE-START | Running 2 tests 16:56:51 INFO - TEST-START | dromaeo_css 16:56:51 INFO - Initialising browser for dromaeo_css test... 16:56:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54554/getInfo.html -profile /tmp/tmpK1c0L8/profile 16:56:51 INFO - TEST-INFO | started process 24488 (/builds/slave/test/build/application/firefox/firefox http://localhost:54554/getInfo.html) 16:56:58 INFO - TEST-INFO | 24488: exit 0 16:56:58 INFO - Browser initialized. 16:56:58 INFO - Running cycle 1/1 for dromaeo_css test... 16:56:58 INFO - TEST-INFO | started process 24718 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK1c0L8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:56:59 INFO - PID 24718 | 16:56:59 INFO - PID 24718 | (/builds/slave/test/build/application/firefox/firefox:24772): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:56:59 INFO - PID 24718 | 16:57:09 INFO - PID 24718 | RSS: Main: 173080576 16:57:09 INFO - PID 24718 | 17:00:10 INFO - PID 24718 | Cycle 1(1): loaded http://localhost:54554/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54554/tests/dromaeo/cssquery-ext.html) 17:00:10 INFO - PID 24718 | RSS: Main: 174862336 17:00:10 INFO - PID 24718 | 17:03:12 INFO - PID 24718 | Cycle 1(1): loaded http://localhost:54554/tests/dromaeo/cssquery-ext.html (next: http://localhost:54554/tests/dromaeo/cssquery-jquery.html) 17:03:13 INFO - PID 24718 | RSS: Main: 170819584 17:03:13 INFO - PID 24718 | 17:06:15 INFO - PID 24718 | Cycle 1(1): loaded http://localhost:54554/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54554/tests/dromaeo/cssquery-mootools.html) 17:06:16 INFO - PID 24718 | RSS: Main: 174272512 17:06:16 INFO - PID 24718 | 17:09:18 INFO - PID 24718 | Cycle 1(1): loaded http://localhost:54554/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54554/tests/dromaeo/cssquery-prototype.html) 17:09:18 INFO - PID 24718 | RSS: Main: 172630016 17:09:18 INFO - PID 24718 | 17:12:22 INFO - PID 24718 | Cycle 1(1): loaded http://localhost:54554/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54554/tests/dromaeo/cssquery-yui.html) 17:12:23 INFO - PID 24718 | RSS: Main: 173867008 17:12:23 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | Cycle 1(1): loaded http://localhost:54554/tests/dromaeo/cssquery-yui.html (next: http://localhost:54554/tests/dromaeo/cssquery-dojo.html) 17:15:25 INFO - PID 24718 | RSS: Main: 176521216 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | __start_tp_report 17:15:25 INFO - PID 24718 | _x_x_mozilla_page_load 17:15:25 INFO - PID 24718 | _x_x_mozilla_page_load_details 17:15:25 INFO - PID 24718 | |i|pagename|runs| 17:15:25 INFO - PID 24718 | |0;dojo.html;3156.636986746525;3172.539981702652;3179.984100079499;3189.3461840322734;3193.8403079846003;6150.739098484811;6181.969090154555;6201.162843016192;6215.098810672461;6258.687065646723;6191.411815877495;6196.163517925079;6221.564490485668;6341.3341599132;6351.142595749572;5663.575231857607;5689.032864413039;5717.99999999999;5752.568557358194;5759.280089988752;5518.420565840589;5641.18202860585;5673.602847800656;5676.659400435967;5759.395263497336;5534.584906132036;5608.747606357703;5693.146028095798;5773.336066352339;5792.623479473821;5672.149177623348;5710.457506536873;5741.110127930177;5742.82771516851;5750.511206547449;5624.3250809902665;5670.432956704338;5731.942680573225;5756.424357564252;5766.250387449647;6029.276486821565;6057.485113765291;6075.513958883279;6083.574149809515;6105.664188469633;6653.53425260232;6673.866522669519;6673.93326066738;6696.330366963313;6705.564138331041;6698.665066746692;6721.462283017347;6749.662516874137;6753.898691519631;6758.155230596176;6171.938280617182;6248.156498872685;6259.155014073133;6264.59280146789;6274.435300822948;6895.482838787111;6898.655067246617;6923.965380173167;6924.896126558106;6926.826829329207;6497.122888410098;6499.610023398611;6555.672216389162;6564.606123632598;6597.142371491771;6631.303713110054;6649.534532582769;6674.199096108498;6746.291639377893;6759.986002099725;6621.304762999912;6657.068010478537;6680.064790929274;6763.086983257197;6766.83082922921;6610.7025183866845;6617.2059352877;6680.064790929274;6701.530892837454;6738.764143255061;5518.586106042063;5526.47498487643;5526.640768350045;5528.308961379827;5530.446955304437;4637.675362724577;4700.224462963641;4716.292556116541;4716.6698331116495;4741.454732705783;5781.797637082682;5861.648301101947;5866.061430171152;5874.530037596982;5875.823725288248;6680.164979377578;6714.261431242559;6781.694823732944;6803.829904252433;6856.142982127234;5767.336756272998;5817.214676018768;5834.67909264986;5850.385709500526;5851.063829787214;5896.321922978912;5952.672603006876;5954.612950158225;5972.492338151219;5976.043832986701;6529.053287273318;6529.836754081091;6530.706118224692;6533.314002029813;6534.509911756542;6703.363180497845;6734.427573656294;6740.191177058784;6743;6747.122874026345;6489.513286503626;6532.967335163294;6545;6549.705263263069;6557.1147895035465;6643;6662.966685166543;6736.427403670743;6748.156480439945;6758.594484330956;6612.933870661232;6700.262971073275;6701.899471507833;6778.389944904982;6831;6528.249251336157;6545.738170473128;6569.244536878129;6579.144711187515;6580.8354791130605;5513.614047016831;6511.7395304189195;6524.2823289439075;6530.771423000362;6552.967235163794;6387.968060159669;6432.581882177644;6441.452476539546;6517.4460170886005;6546.509011824037;6497.545171837926;6501.122348482894;6592.472602191909;6633.2040155181685;6637.933620663925;6231.345708700521;6291.937080629318;6321.0834429008455;6374.745010199726;6442.677866106769 17:15:25 INFO - PID 24718 | |1;ext.html;815.4129027100486;868.8218915122399;900.2482926756159;903.0112027330072;1188.328594344196;30416;30982.53526197106;41000.79499602502;42276.154476910444;42416.151676966445;42156;42278.154436911245;42708.359374609405;42742.57257427432;42768.3584746229;7723.459357844953;7746.457747957631;7935.960320198391;7978.960105199466;7981.960090199541;7719.07371115467;7856.685732570691;7861.606919654023;7887.526748395115;7889.092754333239;7848.607569621496;7870.803229919269;7878.763637090897;7903.288704016637;7912.525248485109;7806.804829879242;7844;7868.527888326718;7880.172581878935;7894.407919406068;7707.383409327241;7714;7808.375329973588;7825.608719563999;7829.41279404047;37794.81102594469;38224;38260.61739382598;38350.232995340215;38361.616383836365;3492.842822073013;3536.29274145172;3542.256126213498;3544.7873127612424;3548.3258180945536;31296;31332.21669458259;31573.368532629218;31640.050798476084;31658;4612.654050946159;4657.417822772153;4810.903781924377;4813.205821039524;4885.218365061573;172801;173563;174305;174868;175448;47661.285080723814;47875.76062119667;47949.520504794506;48947.26579101316;49734.50265497371;51449.22826157611;51473.22790158151;51475.48524514707;51505.48494515082;51594.484055158966;16325.34698612066;16635;16740.6651866962;16786.74819877703;16838;13599.456021759019;13601.795973060214;13685.315734213249;13722;13735.038547301592;14436;14527.78208326876;14675.119492830465;14697.485588004582;14798.48205312809;5784.971075144597;5854.560907931922;5988.652553175588;6029.457348838626;6142;961.4135377419768;979.8628192053118;991.940483570988;1013.1833502862055;1018.8267994440963;22406.775932240795;22441.32676019722;22786.316410507712;22884.542309154058;23053.654195187086;1076.6285631457135;1095.611058074388;1112.9387883666477;1123.152020224725;1131.3268605179906;11734.413279335999;12022.759544809054;12378.195417297848;15196.772048419283;15881.602959926095;206906;207458;207949;208202;208211;55126.44873551293;55640.72179639076;55755.442445575834;55908.72045639746;55938.16092758612;1440.3086518471193;1441.119339262252;1492.276246020679;1492.9552113436614;1497.0344128037354;25620.75232144176;26509.60235596506;26609.600855987563;26882.596761048208;27078.593821093094;37109.44335835019;37311.81344093262;37369;49821.501784983135;52413;200884;200935;200994;202515;202537;34866;36823.815880920425;37057.81471092627;37442.812785935894;37691;45432.545674544155;45844.54155458403;45986;46264.30603541017;46679.53320466752;32132.518012229368;32439.675603243664;32441.67558324481;32641;32742.017739467854;29691.703082968892;30924.845375772977;31906.52140217852;32275.677243228205;32290.35419291648;36579;36712.63287367092;36781;36859.815700921325;37040;29865.701342986293;31611.683883160873;31878.36243275075;32456;32863.8356808224;22261.443463912885;23574;23933.042678292673;23986.160484382963;24249.272521824372 17:15:25 INFO - PID 24718 | |2;jquery.html;556.698053548577;557.9358373787013;575.3383608849823;576.5013263527051;585.2479563760567;22168;22391.888040559796;23074.769252307473;23119.537609247804;23206.41983950398;25593.616095758578;26241;26244;26245.606315905276;26295.34261643455;6544.280129185798;6703.664816759167;6706.094677218586;6706.295838936914;6708.060871477997;6360.7137678804565;6447.516436267275;6516.413522782948;6658.93341066588;6691.3308669133185;6652.334766523358;6658.101156343904;6661.333866613349;6662;6664.333566643321;6613.636250006248;6625.668716564152;6630.73477060917;6650.866982660368;6652.334766523358;6135.355787642323;6146.323904370515;6165.10605962133;6181.505479561624;6188.133661287423;26565;28870.278243043882;29242.976495822542;29520.557191642143;29767;1862.8789128706592;1902.0869982408383;1908.265317852623;1908.408393398051;1913.569446874456;18888.71666924997;19040.90479547607;19137.425877223704;19169;19175.041247937686;2295.724513058427;2321.442853715113;2358.056777289081;2362.1850461590725;2414.8309618326725;123748;128054.3597282017;129679;129750;129972;14742.96799224044;14743.11541307525;15127.319270632628;15171.620709482131;15535.611609709622;22975.540489190458;23063.07747690107;23107.537849242923;23163.7683623165;23191.652125218134;10261.486925653688;10325.380477171393;10546.26176167661;10549.894501055045;10630.840537391945;9956.103950644332;10050.633037932228;10063.295569310078;10100.595976161017;10101.545430455648;7605.771826845204;8874.689385871463;9217.76955576116;9252.935912370029;9307.06929307064;2745.917622471329;2779.1662501249543;2785.777137828969;2845.2744550139996;2874.1664917174203;935.3779736475303;947.7583216279804;963.3016063354153;965.4110992294694;966.5843687214565;18719.438416847242;18864.811351886307;19102.426927192206;19477.610447790965;23454.530909381716;1001.8378680730318;1009.1119814563284;1025.9127974122134;1039.6621098142982;1043.9008294212038;13020.544280950311;13767.242801645805;13836.893048556092;13882.375293111834;13957.162570245819;508302;513652;517912;523269;524505;118836;121412;125449;126117;126764;1227.2391117507202;1246.962591122268;1291.6964513339358;1293.1465232946023;1297.5004623219938;19923.501912452306;20604.04261595146;20839.79160208439;21436.678449823576;21474.248401306504;52910.73544632406;55762.282424550576;56182;56642.716786415804;56886.431135688115;60613.69693151506;63402.36597633964;63769;63997.68001160151;64137.35862641501;12745.426455809325;13355.799663005257;13379.3979270935;15203.923980380026;15421.84578154249;22928.541429171655;23949.28152155438;24139.758602413753;24187.51624967526;24602.630960535247;18820.811791881904;19587.51031224231;19644.50888727736;19672.508187295432;19707;16823.7476437856;17105.572360690585;17216;17408.651826963644;17879.28482860671;19130.23479060822;19237.13432895495;19780.604387912448;19797.505062373555;19822.603547929248;14598.781018284946;14701.779473307695;14765.557033288589;14779.704405911607;14813;17118.315267389164;17301.913490432467;18714;18958.43124706261;19018 17:15:25 INFO - PID 24718 | |3;mootools.html;1332.613542072799;1356.7218720162364;1379.1380387257964;1392.3525560614305;1399.2234309957983;13864.237466939314;13915.304234788271;14343;14365.92817035916;14366.497172598962;14071.437142514287;14169.362378692958;14176.362063707133;14188.503402380918;14246.216458094827;2517.609770485573;2572.845629262241;2594.8702564871683;2607.4915391498666;2612.1771641932824;2604.479104179172;2679.9464010719776;2716.8369897806097;2758.1174024312227;2758.213909035925;2646.7220941801124;2656.402309480361;2684.6912605050475;2684.9865750671315;2685.516607010737;2638.8027102826895;2646.140004498536;2657.9999999999905;2673.8128331016933;2674.505216534947;2581.948361032769;2604.791616670681;2606.4396154826804;2609.7259787722205;2626.763591276775;13283.601491955256;13323.533676321282;13347.399367028507;13409.262490563113;13413.798793018113;7297.744578939712;7497.925020749777;7515.586642734647;7525.172231054579;7534.660940257701;4751.904961900743;4872.415310162768;4979.053979743873;5182.766775495112;5183.118869792144;1814.936477223304;1819.272291083564;1836.8071352508061;1877.5400026993475;1887.7168424736262;22042.33872983813;22410.55178896413;22549.43626409353;22588;22656.20703275394;6585.934140658628;6678.866422671519;6766.25571187169;6893.966279947427;7026.789196324118;15637.452689155825;15647.295871685801;15665.138417387154;15695.921520392554;15779.842201578067;7560.130584982688;7577;7581.12817026038;7581.6209189540305;7588.089429268523;6864;7025.297470252934;7058.153021637334;7075.787726368218;7089.503734738519;5095.770690318945;5249.343832020997;5253.395859476209;5258.342707161605;5273.31446911899;1509.7433436315853;1511.7127745728276;1521;1531.4333696532353;1534.5703203103149;830.5805568188147;859.1837754133599;865.952372619512;871.7515508080166;890.2121622364255;6430.164078669743;6498.252700939358;6772.8984065239065;6787.355201255873;6814.761483348059;684.3293572299175;692.3630260160664;716.7276434954683;725.2203880828131;727.3017902813358;4293.656507479394;4672.556107169813;4702.907206293166;4779.3070004849105;4788.784504697297;17048.659026819394;18218.635627287644;18454.446366609023;18462.261509539738;18502;18372.540686482946;18465;18498.537536561424;18520.81479185191;18539.907300463412;10287.485625718684;10505.212109091848;10514.526846291783;10549.578016879239;10597.099246564128;6862.691178897061;7287.140863609797;7364.96317518409;7485.7754267372065;7559.622018898923;15552.766708499155;15815.288312026216;15905.522834314988;16170.67658646797;16197.352105915632;12913.354332283161;15583.688326233185;16553.503394898173;16581.08804015791;16736.748948765533;9928.602855885973;10193.643222487177;10199.949000254952;10216.54025568866;10218.897811022092;12479.126461147633;12983.28591927453;13019.153755005895;13053.34733263352;13076.411561479947;9703.417794932328;10049.296549241775;10139.391636501834;10331.741706457398;10591.576336946437;9594.904050959402;10179.287449878437;10203.693889183336;10256.58973641046;10431.11335536488;8756.25571826402;8849.336299777671;8856.07011263808;8856.955715221382;8879.378443509151;6371.904421433589;6437.356264373525;6640.501962352746;6655.966720166368;6724.327567243236;6335.239771227482;6506.251781045051;6555.737770489127;6608.900866486911;6704.262531121671 17:15:25 INFO - PID 24718 | |4;prototype.html;494.45312613392804;498.1015383410966;510.839085688008;513.7970501651847;520.8073012985194;6252;6262;6268.780592679257;6278.403551662596;6302.8739425211525;5884.73397264518;6130.724117414716;6143.0035238547425;6145.692715364236;6168.75324987;4951.851444456672;4967.677100988424;4976.3530741003615;4992.500749924997;4993;4823.493533179018;4995.35060442142;4997.15048441764;5014.749262536877;5032.270320803481;4883.438404583465;4901.975490122544;4913.361263035818;4919.458859525449;4933.432655244657;4912.164931961575;4933.876653083684;4948.208286674151;4968.130577149021;5002.699838009731;4402.449693788277;4407.933880991788;4434.55654434554;4444.488883778342;4471;6017.548683848729;6060.2424696912885;6074.23847084345;6101.298350689639;6102.633841969452;5671.744771485293;5673.744681489302;5678.4037676044245;5681.914771278434;5739.799107031234;6434.528003037891;6483.7730679425995;6491;6511.283758786577;6548.70530826114;4816.879578010543;4833.081714474239;4847.927281090787;4863.859901643301;4900.362952816146;6783.525153239275;6907.240203577602;6930.7227710891;6950.895736563955;6987.253186703324;4949.010197960422;4970.3290055842;4975.203967365204;4983.725895075806;4991.401031876198;5032.547070763649;5053;5058.519440653132;5065.417476990193;5072.54347108762;5645.915311270334;5658.207850900877;5684;5693.772249109989;5733.971330143406;6431.485481161496;6448.742050318028;6516.185476815398;6563.540552161303;6583.568555941271;1679.2863033210836;1718.3470281293028;1732.3763445159732;1733.298014304209;1745.1884873533868;1194.4266751959108;1212.090931801149;1221.3099598726692;1234.4444999750149;1237.4678888078213;5681.636567044853;5735.655860648374;5774.971125144431;5779.826605201851;5795.9420405796245;6493.577917435352;6525.804225873231;6573.370651739343;6654.733810647616;6683.298253683293;5546.889062218733;5729.799457019068;5757.280339957505;5807.477327040587;5821.738021788945;3507.7030637469406;3524.3656141894194;3554.2358392945125;3556.253186830743;3581.534400527915;6403.231612206472;6414.647194404352;6423.807285781434;6490.643014634241;6512.176590554653;6723.394894459522;6814.557053791487;6851;6859.622720750307;6896.965515172392;6337.302896681454;6465.191851018622;6480.51396145291;6487.124238227968;6514.283428822826;6527.738890444329;6537.70580323877;6606.867862642816;6607.9669601651685;6669.366410191024;6539.901901471387;6580.177477815273;6589.868202635825;6600.34991252187;6618.7352505899225;6380.840478988062;6396.616203027833;6443.7744678936015;6493.318201588765;6533.18335208099;4855.587275081658;4929.482404347492;4983.725895075806;4987.2768448574025;5015.874603134951;6613.908705063611;6623.437007854386;6716.597004179765;6730.6779161189315;6742.494312926452;6420.614763114041;6475.543002824327;6535.477161827138;6536.215654121535;6539.967300163468;6332.1636725349135;6457.741690332334;6541.6075035498025;6575.651991341859;6582.440492558187;6219.626822390671;6341.55609107358;6422.454091402283;6431.5819471734185;6447.2263328400895;6258.780942666985;6320.273168585672;6354.745810167541;6399.168108145911;6492.285848556749;6194.38056194377;6286.119943207863;6291.937080629318;6311.715972781144;6329.1139240505445 17:15:25 INFO - PID 24718 | |5;yui.html;884.8230353929216;889.7908991387023;911.6444586611227;914.5564401265385;916.4318122763888;18335.174917128727;19184.42446726597;19191.42425727227;19211.807881921195;19234.80765192348;16565.66868662626;16830.410935617256;18916.905415472902;21326.680099798516;21432.35702928911;1036.7926414717067;1071.3411252079954;1073.4364458659227;1073.8013467508497;1075.021730225495;1063.3991794636045;1063.4842101580766;1063.7393838509538;1065.8507808906759;1066.5253961986919;1004.8241557727405;1033.8397548379976;1092.983605245922;1094.6770702642739;1095.4905968724543;1067.9359238445718;1078.7734575739064;1085.978280434387;1086.1528008153573;1086.5545126498178;792.0416296281506;795.7334293011847;797.4338219863904;797.57728403946;797.8882956386111;18942.905285473622;19311.324103656305;19331.51671208217;19339.4198174055;20316.08577614011;20122.899385503126;20315.187392504282;20372.185112595478;20548.897255513777;20588;15382;15448.304826282843;15648.530544083696;15650.68698626021;15659.843401565953;3397.0997685613333;3458.3429148461964;3464.5842498900042;3464.7574669773126;3465.5841299044278;118471;118832;120763.39618301853;125250;125400.37299813534;16499;16850.157492125345;16867.57831054209;16885.577860553338;16926.57683557921;29478;29562.96529621496;30055.69944300529;30103;30403.695963040085;8067.39494537912;8120.959395202986;8211.58942052895;8278.048024477142;8294.170582941779;3917.0990672144994;3966.6033396660105;3993.7004724645767;3994.1811928555157;3995.360742281221;4915;5008.949910500921;5009.599232061426;5018.222175562793;5029.949700502948;983.4148681534475;999.3554157568473;1003.2876657573205;1009.9293049486612;1011.3577878047493;641.7657554992394;657.6317262333109;680.1532092544813;685.256496701075;687.9508749157575;10697.358158510358;11350.262232954832;11588.304701717923;12655.87344126547;12668.303243321705;655.1646650520624;672.3243140643622;684.4599650785732;686.3411125319649;713.5290708132711;5216.791328346823;5228.451012643618;5298.788048478018;5626.493615574618;5631.099024156116;99573;100515.49742251242;102286;102917.48541257245;106547.9345206538;82718.17281827105;87332.56333718418;89286;90569.54715226381;91291.40528229628;72113;76215.61892190503;76823;78373.60813196012;78715.60642196752;11464;11852.525898963944;12194.268343899394;12322.630321090382;12335.629931102081;32595.34809303848;34137.146571335914;34741.65258347384;35736.821315893256;35759.82120089383;63102.68448657727;64229.678851605444;64869;65169.67415162894;67370;18567.25730970746;18873;19104.23583056662;19186.13662385224;19247.90376048111;20438.89780551088;20460.590788183854;20566.177352905717;20808;20866.895665521577;18537.629247415247;18823.717644235072;18826;18874.905625471783;19003.144858481126;18044.90977545104;18395.172217249987;18421.447356579323;18438.446846594623;18728.812711872706;17215.741763873302;17249.31002759926;17318.480445586654;17323.91338043352;17474.388396406066;12971.41628626733;13288.86711132915;13595.660108496972;13880.583582492542;13966.650833729238;12901.161424507378;13563.186208827501;13776.586702398945;13855.515056972958;13924.58226253214 17:15:25 INFO - PID 24718 | __end_tp_report 17:15:25 INFO - PID 24718 | __start_cc_report 17:15:25 INFO - PID 24718 | _x_x_mozilla_cycle_collect,354 17:15:25 INFO - PID 24718 | __end_cc_report 17:15:25 INFO - PID 24718 | __startTimestamp1501200925384__endTimestamp 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | ------- Summary: start ------- 17:15:25 INFO - PID 24718 | Number of tests: 6 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | [#0] dojo.html Cycles:165 Average:6166.25 Median:6441.45 stddev:724.86 (11.3%) stddev-sans-first:687.56 17:15:25 INFO - PID 24718 | Values: 3156.6 3172.5 3180.0 3189.3 3193.8 6150.7 6182.0 6201.2 6215.1 6258.7 6191.4 6196.2 6221.6 6341.3 6351.1 5663.6 5689.0 5718.0 5752.6 5759.3 5518.4 5641.2 5673.6 5676.7 5759.4 5534.6 5608.7 5693.1 5773.3 5792.6 5672.1 5710.5 5741.1 5742.8 5750.5 5624.3 5670.4 5731.9 5756.4 5766.3 6029.3 6057.5 6075.5 6083.6 6105.7 6653.5 6673.9 6673.9 6696.3 6705.6 6698.7 6721.5 6749.7 6753.9 6758.2 6171.9 6248.2 6259.2 6264.6 6274.4 6895.5 6898.7 6924.0 6924.9 6926.8 6497.1 6499.6 6555.7 6564.6 6597.1 6631.3 6649.5 6674.2 6746.3 6760.0 6621.3 6657.1 6680.1 6763.1 6766.8 6610.7 6617.2 6680.1 6701.5 6738.8 5518.6 5526.5 5526.6 5528.3 5530.4 4637.7 4700.2 4716.3 4716.7 4741.5 5781.8 5861.6 5866.1 5874.5 5875.8 6680.2 6714.3 6781.7 6803.8 6856.1 5767.3 5817.2 5834.7 5850.4 5851.1 5896.3 5952.7 5954.6 5972.5 5976.0 6529.1 6529.8 6530.7 6533.3 6534.5 6703.4 6734.4 6740.2 6743.0 6747.1 6489.5 6533.0 6545.0 6549.7 6557.1 6643.0 6663.0 6736.4 6748.2 6758.6 6612.9 6700.3 6701.9 6778.4 6831.0 6528.2 6545.7 6569.2 6579.1 6580.8 5513.6 6511.7 6524.3 6530.8 6553.0 6388.0 6432.6 6441.5 6517.4 6546.5 6497.5 6501.1 6592.5 6633.2 6637.9 6231.3 6291.9 6321.1 6374.7 6442.7 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | [#1] ext.html Cycles:180 Average:37113.07 Median:20063.66 stddev:50450.87 (251.5%) stddev-sans-first:50518.77 17:15:25 INFO - PID 24718 | Values: 815.4 868.8 900.2 903.0 1188.3 30416.0 30982.5 41000.8 42276.2 42416.2 42156.0 42278.2 42708.4 42742.6 42768.4 7723.5 7746.5 7936.0 7979.0 7982.0 7719.1 7856.7 7861.6 7887.5 7889.1 7848.6 7870.8 7878.8 7903.3 7912.5 7806.8 7844.0 7868.5 7880.2 7894.4 7707.4 7714.0 7808.4 7825.6 7829.4 37794.8 38224.0 38260.6 38350.2 38361.6 3492.8 3536.3 3542.3 3544.8 3548.3 31296.0 31332.2 31573.4 31640.1 31658.0 4612.7 4657.4 4810.9 4813.2 4885.2 172801.0 173563.0 174305.0 174868.0 175448.0 47661.3 47875.8 47949.5 48947.3 49734.5 51449.2 51473.2 51475.5 51505.5 51594.5 16325.3 16635.0 16740.7 16786.7 16838.0 13599.5 13601.8 13685.3 13722.0 13735.0 14436.0 14527.8 14675.1 14697.5 14798.5 5785.0 5854.6 5988.7 6029.5 6142.0 961.4 979.9 991.9 1013.2 1018.8 22406.8 22441.3 22786.3 22884.5 23053.7 1076.6 1095.6 1112.9 1123.2 1131.3 11734.4 12022.8 12378.2 15196.8 15881.6 206906.0 207458.0 207949.0 208202.0 208211.0 55126.4 55640.7 55755.4 55908.7 55938.2 1440.3 1441.1 1492.3 1493.0 1497.0 25620.8 26509.6 26609.6 26882.6 27078.6 37109.4 37311.8 37369.0 49821.5 52413.0 200884.0 200935.0 200994.0 202515.0 202537.0 34866.0 36823.8 37057.8 37442.8 37691.0 45432.5 45844.5 45986.0 46264.3 46679.5 32132.5 32439.7 32441.7 32641.0 32742.0 29691.7 30924.8 31906.5 32275.7 32290.4 36579.0 36712.6 36781.0 36859.8 37040.0 29865.7 31611.7 31878.4 32456.0 32863.8 22261.4 23574.0 23933.0 23986.2 24249.3 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | [#2] jquery.html Cycles:180 Average:35498.58 Median:12612.13 stddev:86595.42 (686.6%) stddev-sans-first:86798.60 17:15:25 INFO - PID 24718 | Values: 556.7 557.9 575.3 576.5 585.2 22168.0 22391.9 23074.8 23119.5 23206.4 25593.6 26241.0 26244.0 26245.6 26295.3 6544.3 6703.7 6706.1 6706.3 6708.1 6360.7 6447.5 6516.4 6658.9 6691.3 6652.3 6658.1 6661.3 6662.0 6664.3 6613.6 6625.7 6630.7 6650.9 6652.3 6135.4 6146.3 6165.1 6181.5 6188.1 26565.0 28870.3 29243.0 29520.6 29767.0 1862.9 1902.1 1908.3 1908.4 1913.6 18888.7 19040.9 19137.4 19169.0 19175.0 2295.7 2321.4 2358.1 2362.2 2414.8 123748.0 128054.4 129679.0 129750.0 129972.0 14743.0 14743.1 15127.3 15171.6 15535.6 22975.5 23063.1 23107.5 23163.8 23191.7 10261.5 10325.4 10546.3 10549.9 10630.8 9956.1 10050.6 10063.3 10100.6 10101.5 7605.8 8874.7 9217.8 9252.9 9307.1 2745.9 2779.2 2785.8 2845.3 2874.2 935.4 947.8 963.3 965.4 966.6 18719.4 18864.8 19102.4 19477.6 23454.5 1001.8 1009.1 1025.9 1039.7 1043.9 13020.5 13767.2 13836.9 13882.4 13957.2 508302.0 513652.0 517912.0 523269.0 524505.0 118836.0 121412.0 125449.0 126117.0 126764.0 1227.2 1247.0 1291.7 1293.1 1297.5 19923.5 20604.0 20839.8 21436.7 21474.2 52910.7 55762.3 56182.0 56642.7 56886.4 60613.7 63402.4 63769.0 63997.7 64137.4 12745.4 13355.8 13379.4 15203.9 15421.8 22928.5 23949.3 24139.8 24187.5 24602.6 18820.8 19587.5 19644.5 19672.5 19707.0 16823.7 17105.6 17216.0 17408.7 17879.3 19130.2 19237.1 19780.6 19797.5 19822.6 14598.8 14701.8 14765.6 14779.7 14813.0 17118.3 17301.9 18714.0 18958.4 19018.0 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | [#3] mootools.html Cycles:180 Average:8363.81 Median:2610.95 stddev:5689.43 (217.9%) stddev-sans-first:5680.86 17:15:25 INFO - PID 24718 | Values: 1332.6 1356.7 1379.1 1392.4 1399.2 13864.2 13915.3 14343.0 14365.9 14366.5 14071.4 14169.4 14176.4 14188.5 14246.2 2517.6 2572.8 2594.9 2607.5 2612.2 2604.5 2679.9 2716.8 2758.1 2758.2 2646.7 2656.4 2684.7 2685.0 2685.5 2638.8 2646.1 2658.0 2673.8 2674.5 2581.9 2604.8 2606.4 2609.7 2626.8 13283.6 13323.5 13347.4 13409.3 13413.8 7297.7 7497.9 7515.6 7525.2 7534.7 4751.9 4872.4 4979.1 5182.8 5183.1 1814.9 1819.3 1836.8 1877.5 1887.7 22042.3 22410.6 22549.4 22588.0 22656.2 6585.9 6678.9 6766.3 6894.0 7026.8 15637.5 15647.3 15665.1 15695.9 15779.8 7560.1 7577.0 7581.1 7581.6 7588.1 6864.0 7025.3 7058.2 7075.8 7089.5 5095.8 5249.3 5253.4 5258.3 5273.3 1509.7 1511.7 1521.0 1531.4 1534.6 830.6 859.2 866.0 871.8 890.2 6430.2 6498.3 6772.9 6787.4 6814.8 684.3 692.4 716.7 725.2 727.3 4293.7 4672.6 4702.9 4779.3 4788.8 17048.7 18218.6 18454.4 18462.3 18502.0 18372.5 18465.0 18498.5 18520.8 18539.9 10287.5 10505.2 10514.5 10549.6 10597.1 6862.7 7287.1 7365.0 7485.8 7559.6 15552.8 15815.3 15905.5 16170.7 16197.4 12913.4 15583.7 16553.5 16581.1 16736.7 9928.6 10193.6 10199.9 10216.5 10218.9 12479.1 12983.3 13019.2 13053.3 13076.4 9703.4 10049.3 10139.4 10331.7 10591.6 9594.9 10179.3 10203.7 10256.6 10431.1 8756.3 8849.3 8856.1 8857.0 8879.4 6371.9 6437.4 6640.5 6656.0 6724.3 6335.2 6506.3 6555.7 6608.9 6704.3 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | [#4] prototype.html Cycles:180 Average:5470.52 Median:6116.68 stddev:1531.43 (25.0%) stddev-sans-first:1489.49 17:15:25 INFO - PID 24718 | Values: 494.5 498.1 510.8 513.8 520.8 6252.0 6262.0 6268.8 6278.4 6302.9 5884.7 6130.7 6143.0 6145.7 6168.8 4951.9 4967.7 4976.4 4992.5 4993.0 4823.5 4995.4 4997.2 5014.7 5032.3 4883.4 4902.0 4913.4 4919.5 4933.4 4912.2 4933.9 4948.2 4968.1 5002.7 4402.4 4407.9 4434.6 4444.5 4471.0 6017.5 6060.2 6074.2 6101.3 6102.6 5671.7 5673.7 5678.4 5681.9 5739.8 6434.5 6483.8 6491.0 6511.3 6548.7 4816.9 4833.1 4847.9 4863.9 4900.4 6783.5 6907.2 6930.7 6950.9 6987.3 4949.0 4970.3 4975.2 4983.7 4991.4 5032.5 5053.0 5058.5 5065.4 5072.5 5645.9 5658.2 5684.0 5693.8 5734.0 6431.5 6448.7 6516.2 6563.5 6583.6 1679.3 1718.3 1732.4 1733.3 1745.2 1194.4 1212.1 1221.3 1234.4 1237.5 5681.6 5735.7 5775.0 5779.8 5795.9 6493.6 6525.8 6573.4 6654.7 6683.3 5546.9 5729.8 5757.3 5807.5 5821.7 3507.7 3524.4 3554.2 3556.3 3581.5 6403.2 6414.6 6423.8 6490.6 6512.2 6723.4 6814.6 6851.0 6859.6 6897.0 6337.3 6465.2 6480.5 6487.1 6514.3 6527.7 6537.7 6606.9 6608.0 6669.4 6539.9 6580.2 6589.9 6600.3 6618.7 6380.8 6396.6 6443.8 6493.3 6533.2 4855.6 4929.5 4983.7 4987.3 5015.9 6613.9 6623.4 6716.6 6730.7 6742.5 6420.6 6475.5 6535.5 6536.2 6540.0 6332.2 6457.7 6541.6 6575.7 6582.4 6219.6 6341.6 6422.5 6431.6 6447.2 6258.8 6320.3 6354.7 6399.2 6492.3 6194.4 6286.1 6291.9 6311.7 6329.1 17:15:25 INFO - PID 24718 | 17:15:25 INFO - PID 24718 | [#5] yui.html Cycles:180 Average:22443.99 Median:19279.61 stddev:29792.96 (154.5%) stddev-sans-first:29832.55 17:15:25 INFO - PID 24718 | Values: 884.8 889.8 911.6 914.6 916.4 18335.2 19184.4 19191.4 19211.8 19234.8 16565.7 16830.4 18916.9 21326.7 21432.4 1036.8 1071.3 1073.4 1073.8 1075.0 1063.4 1063.5 1063.7 1065.9 1066.5 1004.8 1033.8 1093.0 1094.7 1095.5 1067.9 1078.8 1086.0 1086.2 1086.6 792.0 795.7 797.4 797.6 797.9 18942.9 19311.3 19331.5 19339.4 20316.1 20122.9 20315.2 20372.2 20548.9 20588.0 15382.0 15448.3 15648.5 15650.7 15659.8 3397.1 3458.3 3464.6 3464.8 3465.6 118471.0 118832.0 120763.4 125250.0 125400.4 16499.0 16850.2 16867.6 16885.6 16926.6 29478.0 29563.0 30055.7 30103.0 30403.7 8067.4 8121.0 8211.6 8278.0 8294.2 3917.1 3966.6 3993.7 3994.2 3995.4 4915.0 5008.9 5009.6 5018.2 5029.9 983.4 999.4 1003.3 1009.9 1011.4 641.8 657.6 680.2 685.3 688.0 10697.4 11350.3 11588.3 12655.9 12668.3 655.2 672.3 684.5 686.3 713.5 5216.8 5228.5 5298.8 5626.5 5631.1 99573.0 100515.5 102286.0 102917.5 106547.9 82718.2 87332.6 89286.0 90569.5 91291.4 72113.0 76215.6 76823.0 78373.6 78715.6 11464.0 11852.5 12194.3 12322.6 12335.6 32595.3 34137.1 34741.7 35736.8 35759.8 63102.7 64229.7 64869.0 65169.7 67370.0 18567.3 18873.0 19104.2 19186.1 19247.9 20438.9 20460.6 20566.2 20808.0 20866.9 18537.6 18823.7 18826.0 18874.9 19003.1 18044.9 18395.2 18421.4 18438.4 18728.8 17215.7 17249.3 17318.5 17323.9 17474.4 12971.4 13288.9 13595.7 13880.6 13966.7 12901.2 13563.2 13776.6 13855.5 13924.6 17:15:25 INFO - PID 24718 | -------- Summary: end -------- 17:15:25 INFO - PID 24718 | 17:15:26 INFO - TEST-INFO | 24718: exit 0 17:15:26 INFO - TEST-OK | dromaeo_css | took 1114540ms 17:15:26 INFO - TEST-START | kraken 17:15:26 INFO - Initialising browser for kraken test... 17:15:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54554/getInfo.html -profile /tmp/tmpYpODg5/profile 17:15:26 INFO - TEST-INFO | started process 25089 (/builds/slave/test/build/application/firefox/firefox http://localhost:54554/getInfo.html) 17:15:32 INFO - TEST-INFO | 25089: exit 0 17:15:32 INFO - Browser initialized. 17:15:32 INFO - Running cycle 1/1 for kraken test... 17:15:32 INFO - TEST-INFO | started process 25317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYpODg5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:33 INFO - PID 25317 | 17:15:33 INFO - PID 25317 | (/builds/slave/test/build/application/firefox/firefox:25371): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:15:33 INFO - PID 25317 | 17:15:43 INFO - PID 25317 | RSS: Main: 173387776 17:15:43 INFO - PID 25317 | 17:15:46 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54554/tests/kraken/driver.html?testName=audio-beat-detection) 17:15:47 INFO - PID 25317 | RSS: Main: 178577408 17:15:47 INFO - PID 25317 | 17:15:50 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54554/tests/kraken/driver.html?testName=audio-dft) 17:15:50 INFO - PID 25317 | RSS: Main: 178880512 17:15:50 INFO - PID 25317 | 17:15:53 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54554/tests/kraken/driver.html?testName=audio-fft) 17:15:54 INFO - PID 25317 | RSS: Main: 173625344 17:15:54 INFO - PID 25317 | 17:15:56 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54554/tests/kraken/driver.html?testName=audio-oscillator) 17:15:57 INFO - PID 25317 | RSS: Main: 174383104 17:15:57 INFO - PID 25317 | 17:15:59 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54554/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:16:00 INFO - PID 25317 | RSS: Main: 175112192 17:16:00 INFO - PID 25317 | 17:16:03 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54554/tests/kraken/driver.html?testName=imaging-darkroom) 17:16:03 INFO - PID 25317 | RSS: Main: 177606656 17:16:03 INFO - PID 25317 | 17:16:07 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54554/tests/kraken/driver.html?testName=imaging-desaturate) 17:16:08 INFO - PID 25317 | RSS: Main: 176619520 17:16:08 INFO - PID 25317 | 17:16:11 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54554/tests/kraken/driver.html?testName=json-parse-financial) 17:16:11 INFO - PID 25317 | RSS: Main: 177786880 17:16:11 INFO - PID 25317 | 17:16:13 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54554/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:16:14 INFO - PID 25317 | RSS: Main: 177500160 17:16:14 INFO - PID 25317 | 17:16:15 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:16:16 INFO - PID 25317 | RSS: Main: 174989312 17:16:16 INFO - PID 25317 | 17:16:18 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:16:19 INFO - PID 25317 | RSS: Main: 174854144 17:16:19 INFO - PID 25317 | 17:16:22 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:16:23 INFO - PID 25317 | RSS: Main: 175144960 17:16:23 INFO - PID 25317 | 17:16:26 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:16:26 INFO - PID 25317 | RSS: Main: 175063040 17:16:26 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | Cycle 1(1): loaded http://localhost:54554/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54554/tests/kraken/driver.html?testName=ai-astar) 17:16:28 INFO - PID 25317 | RSS: Main: 176283648 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | __start_tp_report 17:16:28 INFO - PID 25317 | _x_x_mozilla_page_load 17:16:28 INFO - PID 25317 | _x_x_mozilla_page_load_details 17:16:28 INFO - PID 25317 | |i|pagename|runs| 17:16:28 INFO - PID 25317 | |0;ai-astar;103;110;105;107;107;120;108;101;109;108 17:16:28 INFO - PID 25317 | |1;audio-beat-detection;152;119;145;177;121;150;145;123;174;128 17:16:28 INFO - PID 25317 | |2;audio-dft;165;164;163;162;164;163;166;178;163;164 17:16:28 INFO - PID 25317 | |3;audio-fft;77;76;74;179;77;75;76;74;160;75 17:16:28 INFO - PID 25317 | |4;audio-oscillator;83;83;132;79;79;81;137;84;79;130 17:16:28 INFO - PID 25317 | |5;imaging-gaussian-blur;113;114;117;118;113;116;114;114;115;113 17:16:28 INFO - PID 25317 | |6;imaging-darkroom;150;159;151;150;151;150;149;151;153;157 17:16:28 INFO - PID 25317 | |7;imaging-desaturate;94;97;94;99;94;94;98;94;96;99 17:16:28 INFO - PID 25317 | |8;json-parse-financial;56;56;55;56;55;60;55;55;54;54 17:16:28 INFO - PID 25317 | |9;json-stringify-tinderbox;53;57;58;54;54;55;62;55;55;54 17:16:28 INFO - PID 25317 | |10;stanford-crypto-aes;81;82;80;81;82;81;92;78;81;83 17:16:28 INFO - PID 25317 | |11;stanford-crypto-ccm;133;129;132;126;123;135;128;142;121;181 17:16:28 INFO - PID 25317 | |12;stanford-crypto-pbkdf2;175;183;172;168;173;175;182;179;175;170 17:16:28 INFO - PID 25317 | |13;stanford-crypto-sha256-iterative;75;71;71;68;70;69;68;72;82;68 17:16:28 INFO - PID 25317 | __end_tp_report 17:16:28 INFO - PID 25317 | __start_cc_report 17:16:28 INFO - PID 25317 | _x_x_mozilla_cycle_collect,956 17:16:28 INFO - PID 25317 | __end_cc_report 17:16:28 INFO - PID 25317 | __startTimestamp1501200988791__endTimestamp 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | ------- Summary: start ------- 17:16:28 INFO - PID 25317 | Number of tests: 14 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#0] ai-astar Cycles:10 Average:107.80 Median:108.00 stddev:5.09 (4.7%) stddev-sans-first:5.10 17:16:28 INFO - PID 25317 | Values: 103.0 110.0 105.0 107.0 107.0 120.0 108.0 101.0 109.0 108.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:147.50 stddev:20.92 (14.2%) stddev-sans-first:21.96 17:16:28 INFO - PID 25317 | Values: 152.0 119.0 145.0 177.0 121.0 150.0 145.0 123.0 174.0 128.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#2] audio-dft Cycles:10 Average:165.20 Median:164.00 stddev:4.64 (2.8%) stddev-sans-first:4.92 17:16:28 INFO - PID 25317 | Values: 165.0 164.0 163.0 162.0 164.0 163.0 166.0 178.0 163.0 164.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.50 stddev:39.90 (52.8%) stddev-sans-first:41.83 17:16:28 INFO - PID 25317 | Values: 77.0 76.0 74.0 179.0 77.0 75.0 76.0 74.0 160.0 75.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:80.00 stddev:25.17 (31.5%) stddev-sans-first:26.20 17:16:28 INFO - PID 25317 | Values: 83.0 83.0 132.0 79.0 79.0 81.0 137.0 84.0 79.0 130.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.50 stddev:1.77 (1.5%) stddev-sans-first:1.76 17:16:28 INFO - PID 25317 | Values: 113.0 114.0 117.0 118.0 113.0 116.0 114.0 114.0 115.0 113.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:151.00 stddev:3.31 (2.2%) stddev-sans-first:3.43 17:16:28 INFO - PID 25317 | Values: 150.0 159.0 151.0 150.0 151.0 150.0 149.0 151.0 153.0 157.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.50 stddev:2.18 (2.3%) stddev-sans-first:2.20 17:16:28 INFO - PID 25317 | Values: 94.0 97.0 94.0 99.0 94.0 94.0 98.0 94.0 96.0 99.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:1.71 (3.1%) stddev-sans-first:1.81 17:16:28 INFO - PID 25317 | Values: 56.0 56.0 55.0 56.0 55.0 60.0 55.0 55.0 54.0 54.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#9] json-stringify-tinderbox Cycles:10 Average:55.70 Median:55.00 stddev:2.67 (4.9%) stddev-sans-first:2.65 17:16:28 INFO - PID 25317 | Values: 53.0 57.0 58.0 54.0 54.0 55.0 62.0 55.0 55.0 54.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:81.50 stddev:3.73 (4.6%) stddev-sans-first:3.93 17:16:28 INFO - PID 25317 | Values: 81.0 82.0 80.0 81.0 82.0 81.0 92.0 78.0 81.0 83.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:132.50 stddev:17.27 (13.0%) stddev-sans-first:18.30 17:16:28 INFO - PID 25317 | Values: 133.0 129.0 132.0 126.0 123.0 135.0 128.0 142.0 121.0 181.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:175.00 stddev:4.89 (2.8%) stddev-sans-first:5.19 17:16:28 INFO - PID 25317 | Values: 175.0 183.0 172.0 168.0 173.0 175.0 182.0 179.0 175.0 170.0 17:16:28 INFO - PID 25317 | 17:16:28 INFO - PID 25317 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:71.00 stddev:4.33 (6.1%) stddev-sans-first:4.39 17:16:28 INFO - PID 25317 | Values: 75.0 71.0 71.0 68.0 70.0 69.0 68.0 72.0 82.0 68.0 17:16:28 INFO - PID 25317 | -------- Summary: end -------- 17:16:28 INFO - PID 25317 | 17:16:29 INFO - PID 25317 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:16:29 INFO - TEST-INFO | 25317: exit 0 17:16:29 INFO - TEST-OK | kraken | took 63350ms 17:16:29 INFO - SUITE-END | took 1177s 17:16:29 INFO - Completed test suite (00:19:38) 17:16:29 INFO - javascript benchmark 17:16:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8414.231513336112, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3156.636986746525, 3172.539981702652, 3179.984100079499, 3189.3461840322734, 3193.8403079846003, 6150.739098484811, 6181.969090154555, 6201.162843016192, 6215.098810672461, 6258.687065646723, 6191.411815877495, 6196.163517925079, 6221.564490485668, 6341.3341599132, 6351.142595749572, 5663.575231857607, 5689.032864413039, 5717.99999999999, 5752.568557358194, 5759.280089988752, 5518.420565840589, 5641.18202860585, 5673.602847800656, 5676.659400435967, 5759.395263497336, 5534.584906132036, 5608.747606357703, 5693.146028095798, 5773.336066352339, 5792.623479473821, 5672.149177623348, 5710.457506536873, 5741.110127930177, 5742.82771516851, 5750.511206547449, 5624.3250809902665, 5670.432956704338, 5731.942680573225, 5756.424357564252, 5766.250387449647, 6029.276486821565, 6057.485113765291, 6075.513958883279, 6083.574149809515, 6105.664188469633, 6653.53425260232, 6673.866522669519, 6673.93326066738, 6696.330366963313, 6705.564138331041, 6698.665066746692, 6721.462283017347, 6749.662516874137, 6753.898691519631, 6758.155230596176, 6171.938280617182, 6248.156498872685, 6259.155014073133, 6264.59280146789, 6274.435300822948, 6895.482838787111, 6898.655067246617, 6923.965380173167, 6924.896126558106, 6926.826829329207, 6497.122888410098, 6499.610023398611, 6555.672216389162, 6564.606123632598, 6597.142371491771, 6631.303713110054, 6649.534532582769, 6674.199096108498, 6746.291639377893, 6759.986002099725, 6621.304762999912, 6657.068010478537, 6680.064790929274, 6763.086983257197, 6766.83082922921, 6610.7025183866845, 6617.2059352877, 6680.064790929274, 6701.530892837454, 6738.764143255061, 5518.586106042063, 5526.47498487643, 5526.640768350045, 5528.308961379827, 5530.446955304437, 4637.675362724577, 4700.224462963641, 4716.292556116541, 4716.6698331116495, 4741.454732705783, 5781.797637082682, 5861.648301101947, 5866.061430171152, 5874.530037596982, 5875.823725288248, 6680.164979377578, 6714.261431242559, 6781.694823732944, 6803.829904252433, 6856.142982127234, 5767.336756272998, 5817.214676018768, 5834.67909264986, 5850.385709500526, 5851.063829787214, 5896.321922978912, 5952.672603006876, 5954.612950158225, 5972.492338151219, 5976.043832986701, 6529.053287273318, 6529.836754081091, 6530.706118224692, 6533.314002029813, 6534.509911756542, 6703.363180497845, 6734.427573656294, 6740.191177058784, 6743.0, 6747.122874026345, 6489.513286503626, 6532.967335163294, 6545.0, 6549.705263263069, 6557.1147895035465, 6643.0, 6662.966685166543, 6736.427403670743, 6748.156480439945, 6758.594484330956, 6612.933870661232, 6700.262971073275, 6701.899471507833, 6778.389944904982, 6831.0, 6528.249251336157, 6545.738170473128, 6569.244536878129, 6579.144711187515, 6580.8354791130605, 5513.614047016831, 6511.7395304189195, 6524.2823289439075, 6530.771423000362, 6552.967235163794, 6387.968060159669, 6432.581882177644, 6441.452476539546, 6517.4460170886005, 6546.509011824037, 6497.545171837926, 6501.122348482894, 6592.472602191909, 6633.2040155181685, 6637.933620663925, 6231.345708700521, 6291.937080629318, 6321.0834429008455, 6374.745010199726, 6442.677866106769], "value": 6112.658794716444, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.4129027100486, 868.8218915122399, 900.2482926756159, 903.0112027330072, 1188.328594344196, 30416.0, 30982.53526197106, 41000.79499602502, 42276.154476910444, 42416.151676966445, 42156.0, 42278.154436911245, 42708.359374609405, 42742.57257427432, 42768.3584746229, 7723.459357844953, 7746.457747957631, 7935.960320198391, 7978.960105199466, 7981.960090199541, 7719.07371115467, 7856.685732570691, 7861.606919654023, 7887.526748395115, 7889.092754333239, 7848.607569621496, 7870.803229919269, 7878.763637090897, 7903.288704016637, 7912.525248485109, 7806.804829879242, 7844.0, 7868.527888326718, 7880.172581878935, 7894.407919406068, 7707.383409327241, 7714.0, 7808.375329973588, 7825.608719563999, 7829.41279404047, 37794.81102594469, 38224.0, 38260.61739382598, 38350.232995340215, 38361.616383836365, 3492.842822073013, 3536.29274145172, 3542.256126213498, 3544.7873127612424, 3548.3258180945536, 31296.0, 31332.21669458259, 31573.368532629218, 31640.050798476084, 31658.0, 4612.654050946159, 4657.417822772153, 4810.903781924377, 4813.205821039524, 4885.218365061573, 172801.0, 173563.0, 174305.0, 174868.0, 175448.0, 47661.285080723814, 47875.76062119667, 47949.520504794506, 48947.26579101316, 49734.50265497371, 51449.22826157611, 51473.22790158151, 51475.48524514707, 51505.48494515082, 51594.484055158966, 16325.34698612066, 16635.0, 16740.6651866962, 16786.74819877703, 16838.0, 13599.456021759019, 13601.795973060214, 13685.315734213249, 13722.0, 13735.038547301592, 14436.0, 14527.78208326876, 14675.119492830465, 14697.485588004582, 14798.48205312809, 5784.971075144597, 5854.560907931922, 5988.652553175588, 6029.457348838626, 6142.0, 961.4135377419768, 979.8628192053118, 991.940483570988, 1013.1833502862055, 1018.8267994440963, 22406.775932240795, 22441.32676019722, 22786.316410507712, 22884.542309154058, 23053.654195187086, 1076.6285631457135, 1095.611058074388, 1112.9387883666477, 1123.152020224725, 1131.3268605179906, 11734.413279335999, 12022.759544809054, 12378.195417297848, 15196.772048419283, 15881.602959926095, 206906.0, 207458.0, 207949.0, 208202.0, 208211.0, 55126.44873551293, 55640.72179639076, 55755.442445575834, 55908.72045639746, 55938.16092758612, 1440.3086518471193, 1441.119339262252, 1492.276246020679, 1492.9552113436614, 1497.0344128037354, 25620.75232144176, 26509.60235596506, 26609.600855987563, 26882.596761048208, 27078.593821093094, 37109.44335835019, 37311.81344093262, 37369.0, 49821.501784983135, 52413.0, 200884.0, 200935.0, 200994.0, 202515.0, 202537.0, 34866.0, 36823.815880920425, 37057.81471092627, 37442.812785935894, 37691.0, 45432.545674544155, 45844.54155458403, 45986.0, 46264.30603541017, 46679.53320466752, 32132.518012229368, 32439.675603243664, 32441.67558324481, 32641.0, 32742.017739467854, 29691.703082968892, 30924.845375772977, 31906.52140217852, 32275.677243228205, 32290.35419291648, 36579.0, 36712.63287367092, 36781.0, 36859.815700921325, 37040.0, 29865.701342986293, 31611.683883160873, 31878.36243275075, 32456.0, 32863.8356808224, 22261.443463912885, 23574.0, 23933.042678292673, 23986.160484382963, 24249.272521824372], "value": 17427.590808056655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.698053548577, 557.9358373787013, 575.3383608849823, 576.5013263527051, 585.2479563760567, 22168.0, 22391.888040559796, 23074.769252307473, 23119.537609247804, 23206.41983950398, 25593.616095758578, 26241.0, 26244.0, 26245.606315905276, 26295.34261643455, 6544.280129185798, 6703.664816759167, 6706.094677218586, 6706.295838936914, 6708.060871477997, 6360.7137678804565, 6447.516436267275, 6516.413522782948, 6658.93341066588, 6691.3308669133185, 6652.334766523358, 6658.101156343904, 6661.333866613349, 6662.0, 6664.333566643321, 6613.636250006248, 6625.668716564152, 6630.73477060917, 6650.866982660368, 6652.334766523358, 6135.355787642323, 6146.323904370515, 6165.10605962133, 6181.505479561624, 6188.133661287423, 26565.0, 28870.278243043882, 29242.976495822542, 29520.557191642143, 29767.0, 1862.8789128706592, 1902.0869982408383, 1908.265317852623, 1908.408393398051, 1913.569446874456, 18888.71666924997, 19040.90479547607, 19137.425877223704, 19169.0, 19175.041247937686, 2295.724513058427, 2321.442853715113, 2358.056777289081, 2362.1850461590725, 2414.8309618326725, 123748.0, 128054.3597282017, 129679.0, 129750.0, 129972.0, 14742.96799224044, 14743.11541307525, 15127.319270632628, 15171.620709482131, 15535.611609709622, 22975.540489190458, 23063.07747690107, 23107.537849242923, 23163.7683623165, 23191.652125218134, 10261.486925653688, 10325.380477171393, 10546.26176167661, 10549.894501055045, 10630.840537391945, 9956.103950644332, 10050.633037932228, 10063.295569310078, 10100.595976161017, 10101.545430455648, 7605.771826845204, 8874.689385871463, 9217.76955576116, 9252.935912370029, 9307.06929307064, 2745.917622471329, 2779.1662501249543, 2785.777137828969, 2845.2744550139996, 2874.1664917174203, 935.3779736475303, 947.7583216279804, 963.3016063354153, 965.4110992294694, 966.5843687214565, 18719.438416847242, 18864.811351886307, 19102.426927192206, 19477.610447790965, 23454.530909381716, 1001.8378680730318, 1009.1119814563284, 1025.9127974122134, 1039.6621098142982, 1043.9008294212038, 13020.544280950311, 13767.242801645805, 13836.893048556092, 13882.375293111834, 13957.162570245819, 508302.0, 513652.0, 517912.0, 523269.0, 524505.0, 118836.0, 121412.0, 125449.0, 126117.0, 126764.0, 1227.2391117507202, 1246.962591122268, 1291.6964513339358, 1293.1465232946023, 1297.5004623219938, 19923.501912452306, 20604.04261595146, 20839.79160208439, 21436.678449823576, 21474.248401306504, 52910.73544632406, 55762.282424550576, 56182.0, 56642.716786415804, 56886.431135688115, 60613.69693151506, 63402.36597633964, 63769.0, 63997.68001160151, 64137.35862641501, 12745.426455809325, 13355.799663005257, 13379.3979270935, 15203.923980380026, 15421.84578154249, 22928.541429171655, 23949.28152155438, 24139.758602413753, 24187.51624967526, 24602.630960535247, 18820.811791881904, 19587.51031224231, 19644.50888727736, 19672.508187295432, 19707.0, 16823.7476437856, 17105.572360690585, 17216.0, 17408.651826963644, 17879.28482860671, 19130.23479060822, 19237.13432895495, 19780.604387912448, 19797.505062373555, 19822.603547929248, 14598.781018284946, 14701.779473307695, 14765.557033288589, 14779.704405911607, 14813.0, 17118.315267389164, 17301.913490432467, 18714.0, 18958.43124706261, 19018.0], "value": 12146.734102314882, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.613542072799, 1356.7218720162364, 1379.1380387257964, 1392.3525560614305, 1399.2234309957983, 13864.237466939314, 13915.304234788271, 14343.0, 14365.92817035916, 14366.497172598962, 14071.437142514287, 14169.362378692958, 14176.362063707133, 14188.503402380918, 14246.216458094827, 2517.609770485573, 2572.845629262241, 2594.8702564871683, 2607.4915391498666, 2612.1771641932824, 2604.479104179172, 2679.9464010719776, 2716.8369897806097, 2758.1174024312227, 2758.213909035925, 2646.7220941801124, 2656.402309480361, 2684.6912605050475, 2684.9865750671315, 2685.516607010737, 2638.8027102826895, 2646.140004498536, 2657.9999999999905, 2673.8128331016933, 2674.505216534947, 2581.948361032769, 2604.791616670681, 2606.4396154826804, 2609.7259787722205, 2626.763591276775, 13283.601491955256, 13323.533676321282, 13347.399367028507, 13409.262490563113, 13413.798793018113, 7297.744578939712, 7497.925020749777, 7515.586642734647, 7525.172231054579, 7534.660940257701, 4751.904961900743, 4872.415310162768, 4979.053979743873, 5182.766775495112, 5183.118869792144, 1814.936477223304, 1819.272291083564, 1836.8071352508061, 1877.5400026993475, 1887.7168424736262, 22042.33872983813, 22410.55178896413, 22549.43626409353, 22588.0, 22656.20703275394, 6585.934140658628, 6678.866422671519, 6766.25571187169, 6893.966279947427, 7026.789196324118, 15637.452689155825, 15647.295871685801, 15665.138417387154, 15695.921520392554, 15779.842201578067, 7560.130584982688, 7577.0, 7581.12817026038, 7581.6209189540305, 7588.089429268523, 6864.0, 7025.297470252934, 7058.153021637334, 7075.787726368218, 7089.503734738519, 5095.770690318945, 5249.343832020997, 5253.395859476209, 5258.342707161605, 5273.31446911899, 1509.7433436315853, 1511.7127745728276, 1521.0, 1531.4333696532353, 1534.5703203103149, 830.5805568188147, 859.1837754133599, 865.952372619512, 871.7515508080166, 890.2121622364255, 6430.164078669743, 6498.252700939358, 6772.8984065239065, 6787.355201255873, 6814.761483348059, 684.3293572299175, 692.3630260160664, 716.7276434954683, 725.2203880828131, 727.3017902813358, 4293.656507479394, 4672.556107169813, 4702.907206293166, 4779.3070004849105, 4788.784504697297, 17048.659026819394, 18218.635627287644, 18454.446366609023, 18462.261509539738, 18502.0, 18372.540686482946, 18465.0, 18498.537536561424, 18520.81479185191, 18539.907300463412, 10287.485625718684, 10505.212109091848, 10514.526846291783, 10549.578016879239, 10597.099246564128, 6862.691178897061, 7287.140863609797, 7364.96317518409, 7485.7754267372065, 7559.622018898923, 15552.766708499155, 15815.288312026216, 15905.522834314988, 16170.67658646797, 16197.352105915632, 12913.354332283161, 15583.688326233185, 16553.503394898173, 16581.08804015791, 16736.748948765533, 9928.602855885973, 10193.643222487177, 10199.949000254952, 10216.54025568866, 10218.897811022092, 12479.126461147633, 12983.28591927453, 13019.153755005895, 13053.34733263352, 13076.411561479947, 9703.417794932328, 10049.296549241775, 10139.391636501834, 10331.741706457398, 10591.576336946437, 9594.904050959402, 10179.287449878437, 10203.693889183336, 10256.58973641046, 10431.11335536488, 8756.25571826402, 8849.336299777671, 8856.07011263808, 8856.955715221382, 8879.378443509151, 6371.904421433589, 6437.356264373525, 6640.501962352746, 6655.966720166368, 6724.327567243236, 6335.239771227482, 6506.251781045051, 6555.737770489127, 6608.900866486911, 6704.262531121671], "value": 6117.740691860117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.45312613392804, 498.1015383410966, 510.839085688008, 513.7970501651847, 520.8073012985194, 6252.0, 6262.0, 6268.780592679257, 6278.403551662596, 6302.8739425211525, 5884.73397264518, 6130.724117414716, 6143.0035238547425, 6145.692715364236, 6168.75324987, 4951.851444456672, 4967.677100988424, 4976.3530741003615, 4992.500749924997, 4993.0, 4823.493533179018, 4995.35060442142, 4997.15048441764, 5014.749262536877, 5032.270320803481, 4883.438404583465, 4901.975490122544, 4913.361263035818, 4919.458859525449, 4933.432655244657, 4912.164931961575, 4933.876653083684, 4948.208286674151, 4968.130577149021, 5002.699838009731, 4402.449693788277, 4407.933880991788, 4434.55654434554, 4444.488883778342, 4471.0, 6017.548683848729, 6060.2424696912885, 6074.23847084345, 6101.298350689639, 6102.633841969452, 5671.744771485293, 5673.744681489302, 5678.4037676044245, 5681.914771278434, 5739.799107031234, 6434.528003037891, 6483.7730679425995, 6491.0, 6511.283758786577, 6548.70530826114, 4816.879578010543, 4833.081714474239, 4847.927281090787, 4863.859901643301, 4900.362952816146, 6783.525153239275, 6907.240203577602, 6930.7227710891, 6950.895736563955, 6987.253186703324, 4949.010197960422, 4970.3290055842, 4975.203967365204, 4983.725895075806, 4991.401031876198, 5032.547070763649, 5053.0, 5058.519440653132, 5065.417476990193, 5072.54347108762, 5645.915311270334, 5658.207850900877, 5684.0, 5693.772249109989, 5733.971330143406, 6431.485481161496, 6448.742050318028, 6516.185476815398, 6563.540552161303, 6583.568555941271, 1679.2863033210836, 1718.3470281293028, 1732.3763445159732, 1733.298014304209, 1745.1884873533868, 1194.4266751959108, 1212.090931801149, 1221.3099598726692, 1234.4444999750149, 1237.4678888078213, 5681.636567044853, 5735.655860648374, 5774.971125144431, 5779.826605201851, 5795.9420405796245, 6493.577917435352, 6525.804225873231, 6573.370651739343, 6654.733810647616, 6683.298253683293, 5546.889062218733, 5729.799457019068, 5757.280339957505, 5807.477327040587, 5821.738021788945, 3507.7030637469406, 3524.3656141894194, 3554.2358392945125, 3556.253186830743, 3581.534400527915, 6403.231612206472, 6414.647194404352, 6423.807285781434, 6490.643014634241, 6512.176590554653, 6723.394894459522, 6814.557053791487, 6851.0, 6859.622720750307, 6896.965515172392, 6337.302896681454, 6465.191851018622, 6480.51396145291, 6487.124238227968, 6514.283428822826, 6527.738890444329, 6537.70580323877, 6606.867862642816, 6607.9669601651685, 6669.366410191024, 6539.901901471387, 6580.177477815273, 6589.868202635825, 6600.34991252187, 6618.7352505899225, 6380.840478988062, 6396.616203027833, 6443.7744678936015, 6493.318201588765, 6533.18335208099, 4855.587275081658, 4929.482404347492, 4983.725895075806, 4987.2768448574025, 5015.874603134951, 6613.908705063611, 6623.437007854386, 6716.597004179765, 6730.6779161189315, 6742.494312926452, 6420.614763114041, 6475.543002824327, 6535.477161827138, 6536.215654121535, 6539.967300163468, 6332.1636725349135, 6457.741690332334, 6541.6075035498025, 6575.651991341859, 6582.440492558187, 6219.626822390671, 6341.55609107358, 6422.454091402283, 6431.5819471734185, 6447.2263328400895, 6258.780942666985, 6320.273168585672, 6354.745810167541, 6399.168108145911, 6492.285848556749, 6194.38056194377, 6286.119943207863, 6291.937080629318, 6311.715972781144, 6329.1139240505445], "value": 5018.845253588017, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.8230353929216, 889.7908991387023, 911.6444586611227, 914.5564401265385, 916.4318122763888, 18335.174917128727, 19184.42446726597, 19191.42425727227, 19211.807881921195, 19234.80765192348, 16565.66868662626, 16830.410935617256, 18916.905415472902, 21326.680099798516, 21432.35702928911, 1036.7926414717067, 1071.3411252079954, 1073.4364458659227, 1073.8013467508497, 1075.021730225495, 1063.3991794636045, 1063.4842101580766, 1063.7393838509538, 1065.8507808906759, 1066.5253961986919, 1004.8241557727405, 1033.8397548379976, 1092.983605245922, 1094.6770702642739, 1095.4905968724543, 1067.9359238445718, 1078.7734575739064, 1085.978280434387, 1086.1528008153573, 1086.5545126498178, 792.0416296281506, 795.7334293011847, 797.4338219863904, 797.57728403946, 797.8882956386111, 18942.905285473622, 19311.324103656305, 19331.51671208217, 19339.4198174055, 20316.08577614011, 20122.899385503126, 20315.187392504282, 20372.185112595478, 20548.897255513777, 20588.0, 15382.0, 15448.304826282843, 15648.530544083696, 15650.68698626021, 15659.843401565953, 3397.0997685613333, 3458.3429148461964, 3464.5842498900042, 3464.7574669773126, 3465.5841299044278, 118471.0, 118832.0, 120763.39618301853, 125250.0, 125400.37299813534, 16499.0, 16850.157492125345, 16867.57831054209, 16885.577860553338, 16926.57683557921, 29478.0, 29562.96529621496, 30055.69944300529, 30103.0, 30403.695963040085, 8067.39494537912, 8120.959395202986, 8211.58942052895, 8278.048024477142, 8294.170582941779, 3917.0990672144994, 3966.6033396660105, 3993.7004724645767, 3994.1811928555157, 3995.360742281221, 4915.0, 5008.949910500921, 5009.599232061426, 5018.222175562793, 5029.949700502948, 983.4148681534475, 999.3554157568473, 1003.2876657573205, 1009.9293049486612, 1011.3577878047493, 641.7657554992394, 657.6317262333109, 680.1532092544813, 685.256496701075, 687.9508749157575, 10697.358158510358, 11350.262232954832, 11588.304701717923, 12655.87344126547, 12668.303243321705, 655.1646650520624, 672.3243140643622, 684.4599650785732, 686.3411125319649, 713.5290708132711, 5216.791328346823, 5228.451012643618, 5298.788048478018, 5626.493615574618, 5631.099024156116, 99573.0, 100515.49742251242, 102286.0, 102917.48541257245, 106547.9345206538, 82718.17281827105, 87332.56333718418, 89286.0, 90569.54715226381, 91291.40528229628, 72113.0, 76215.61892190503, 76823.0, 78373.60813196012, 78715.60642196752, 11464.0, 11852.525898963944, 12194.268343899394, 12322.630321090382, 12335.629931102081, 32595.34809303848, 34137.146571335914, 34741.65258347384, 35736.821315893256, 35759.82120089383, 63102.68448657727, 64229.678851605444, 64869.0, 65169.67415162894, 67370.0, 18567.25730970746, 18873.0, 19104.23583056662, 19186.13662385224, 19247.90376048111, 20438.89780551088, 20460.590788183854, 20566.177352905717, 20808.0, 20866.895665521577, 18537.629247415247, 18823.717644235072, 18826.0, 18874.905625471783, 19003.144858481126, 18044.90977545104, 18395.172217249987, 18421.447356579323, 18438.446846594623, 18728.812711872706, 17215.741763873302, 17249.31002759926, 17318.480445586654, 17323.91338043352, 17474.388396406066, 12971.41628626733, 13288.86711132915, 13595.660108496972, 13880.583582492542, 13966.650833729238, 12901.161424507378, 13563.186208827501, 13776.586702398945, 13855.515056972958, 13924.58226253214], "value": 8931.752169794387, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 105.0, 107.0, 107.0, 120.0, 108.0, 101.0, 109.0, 108.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 145.0, 177.0, 121.0, 150.0, 145.0, 123.0, 174.0, 128.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 163.0, 162.0, 164.0, 163.0, 166.0, 178.0, 163.0, 164.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 74.0, 179.0, 77.0, 75.0, 76.0, 74.0, 160.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 132.0, 79.0, 79.0, 81.0, 137.0, 84.0, 79.0, 130.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 118.0, 113.0, 116.0, 114.0, 114.0, 115.0, 113.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 159.0, 151.0, 150.0, 151.0, 150.0, 149.0, 151.0, 153.0, 157.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 94.0, 99.0, 94.0, 94.0, 98.0, 94.0, 96.0, 99.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 55.0, 60.0, 55.0, 55.0, 54.0, 54.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 57.0, 58.0, 54.0, 54.0, 55.0, 62.0, 55.0, 55.0, 54.0], "value": 55.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 80.0, 81.0, 82.0, 81.0, 92.0, 78.0, 81.0, 83.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 129.0, 132.0, 126.0, 123.0, 135.0, 128.0, 142.0, 121.0, 181.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 183.0, 172.0, 168.0, 173.0, 175.0, 182.0, 179.0, 175.0, 170.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 71.0, 68.0, 70.0, 69.0, 68.0, 72.0, 82.0, 68.0], "value": 71.4, "unit": "score"}]}]} 17:16:30 INFO - Return code: 0 17:16:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:16:30 INFO - # TBPL SUCCESS # 17:16:30 INFO - Running post-action listener: _package_coverage_data 17:16:30 INFO - Running post-action listener: _resource_record_post_action 17:16:30 INFO - [mozharness: 2017-07-28 00:16:30.105493Z] Finished run-tests step (success) 17:16:30 INFO - Running post-run listener: _resource_record_post_run 17:16:30 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 442204160; Read time: 0; Write time: 351924 17:16:30 INFO - TinderboxPrint: CPU usage
13.2% 17:16:30 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:16:30 INFO - TinderboxPrint: I/O write bytes / time
442,204,160 / 351,924 17:16:30 INFO - TinderboxPrint: CPU idle
8,287.0 (86.8%) 17:16:30 INFO - TinderboxPrint: CPU user
1,205.1 (12.6%) 17:16:30 INFO - TinderboxPrint: Swap in / out
0 / 0 17:16:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109547520; Read time: 0; Write time: 105152 17:16:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:16:30 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 332656640; Read time: 0; Write time: 246772 17:16:30 INFO - Running post-run listener: _upload_blobber_files 17:16:30 INFO - Blob upload gear active. 17:16:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:16:30 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 17:16:30 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'] 17:16:30 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 17:16:31 INFO - (blobuploader) - INFO - Open directory for files ... 17:16:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:16:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:16:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:31 INFO - (blobuploader) - INFO - Done attempting. 17:16:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:16:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:16:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:32 INFO - (blobuploader) - INFO - Done attempting. 17:16:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:16:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:16:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:33 INFO - (blobuploader) - INFO - Done attempting. 17:16:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:16:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:16:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:37 INFO - (blobuploader) - INFO - Done attempting. 17:16:37 INFO - (blobuploader) - INFO - Iteration through files over. 17:16:37 INFO - Return code: 0 17:16:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:16:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:16:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d7e8dfce82c5f4f11b9f6b959d77ca3d97c57a1cab941e74f2c132b10a2ff78ab480d62d0bd745db3e1c6300390559ea4cbe54445b70aa7d6078c3c37a0795", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b7f5eac5048d108db62af17a28cde74b86434ca0c22106df3466af5150f8e1c2e0de0032504c3886bac14c9730a25213ff1d6dac5d2193ddaab111980f135b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35f542e6c90543609bd45af44f374b3e424961a7e427a8d877730c47300214143de277d058bbb4dcfbe434e6d8bb098159929cc1bf0c493751bf12e4693c9d64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ade3d2008b026ba6c1da191c8e7fadb944d5c2882a310f3e8f5074d4100760eab374a1562d5016adbe3a4a1ef80af174e60d3c7c7bd59ae8d08602f6746edf5"} 17:16:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:16:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:16:37 INFO - Contents: 17:16:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d7e8dfce82c5f4f11b9f6b959d77ca3d97c57a1cab941e74f2c132b10a2ff78ab480d62d0bd745db3e1c6300390559ea4cbe54445b70aa7d6078c3c37a0795", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b7f5eac5048d108db62af17a28cde74b86434ca0c22106df3466af5150f8e1c2e0de0032504c3886bac14c9730a25213ff1d6dac5d2193ddaab111980f135b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35f542e6c90543609bd45af44f374b3e424961a7e427a8d877730c47300214143de277d058bbb4dcfbe434e6d8bb098159929cc1bf0c493751bf12e4693c9d64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ade3d2008b026ba6c1da191c8e7fadb944d5c2882a310f3e8f5074d4100760eab374a1562d5016adbe3a4a1ef80af174e60d3c7c7bd59ae8d08602f6746edf5"} 17:16:37 INFO - Running post-run listener: copy_logs_to_upload_dir 17:16:37 INFO - Copying logs to upload dir... 17:16:37 INFO - mkdir: /builds/slave/test/build/upload/logs 17:16:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.436470 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-07-27 17:16:36.750178) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:36.753261) ========= 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=1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d7e8dfce82c5f4f11b9f6b959d77ca3d97c57a1cab941e74f2c132b10a2ff78ab480d62d0bd745db3e1c6300390559ea4cbe54445b70aa7d6078c3c37a0795", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b7f5eac5048d108db62af17a28cde74b86434ca0c22106df3466af5150f8e1c2e0de0032504c3886bac14c9730a25213ff1d6dac5d2193ddaab111980f135b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35f542e6c90543609bd45af44f374b3e424961a7e427a8d877730c47300214143de277d058bbb4dcfbe434e6d8bb098159929cc1bf0c493751bf12e4693c9d64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ade3d2008b026ba6c1da191c8e7fadb944d5c2882a310f3e8f5074d4100760eab374a1562d5016adbe3a4a1ef80af174e60d3c7c7bd59ae8d08602f6746edf5"} build_url:https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/M4-l_DlIR0urDflX_RShmA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d7e8dfce82c5f4f11b9f6b959d77ca3d97c57a1cab941e74f2c132b10a2ff78ab480d62d0bd745db3e1c6300390559ea4cbe54445b70aa7d6078c3c37a0795", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b7f5eac5048d108db62af17a28cde74b86434ca0c22106df3466af5150f8e1c2e0de0032504c3886bac14c9730a25213ff1d6dac5d2193ddaab111980f135b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35f542e6c90543609bd45af44f374b3e424961a7e427a8d877730c47300214143de277d058bbb4dcfbe434e6d8bb098159929cc1bf0c493751bf12e4693c9d64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ade3d2008b026ba6c1da191c8e7fadb944d5c2882a310f3e8f5074d4100760eab374a1562d5016adbe3a4a1ef80af174e60d3c7c7bd59ae8d08602f6746edf5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:36.777896) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:36.778258) ========= 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=1258088b6da462a47ba2cd90000001a2-1501199698.752716-629926777 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004363 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 17:16:36.861909) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 17:16:36.862221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 17:16:36.862517) ========= ========= Total master_lag: 0.15 =========