builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1500340282.84 results: success (0) revision: 5e73b9798464c3f7106f0161dc9a49b234f42f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:22.844751) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:22.845160) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:22.845437) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:22.861506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:22.861771) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-17 18:11:23-- 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% 220M=0s 2017-07-17 18:11:23 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124318 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:22.998712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:23.002005) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015963 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:23.051228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 18:11:23.051517) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5e73b9798464c3f7106f0161dc9a49b234f42f9c --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 5e73b9798464c3f7106f0161dc9a49b234f42f9c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-17 18:11:23,840 truncating revision to first 12 chars 2017-07-17 18:11:23,841 Setting DEBUG logging. 2017-07-17 18:11:23,841 attempt 1/10 2017-07-17 18:11:23,841 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5e73b9798464?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-17 18:11:24,683 unpacking tar archive at: mozilla-central-5e73b9798464/testing/mozharness/ program finished with exit code 0 elapsedTime=1.243529 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 18:11:24.310404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:24.310721) ========= script_repo_revision: 5e73b9798464c3f7106f0161dc9a49b234f42f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:24.314136) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:24.314400) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 18:11:24.326967) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-07-17 18:11:24.327236) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:11:25 INFO - MultiFileLogger online at 20170717 18:11:25 in /builds/slave/test 18:11:25 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 18:11:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:25 INFO - {'append_to_log': False, 18:11:25 INFO - 'base_work_dir': '/builds/slave/test', 18:11:25 INFO - 'blob_upload_branch': 'Firefox', 18:11:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:25 INFO - 'branch': 'Firefox', 18:11:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:25 INFO - 'code_coverage': False, 18:11:25 INFO - 'config_files': ('talos/linux_config.py',), 18:11:25 INFO - 'default_actions': ('clobber', 18:11:25 INFO - 'read-buildbot-config', 18:11:25 INFO - 'download-and-extract', 18:11:25 INFO - 'populate-webroot', 18:11:25 INFO - 'create-virtualenv', 18:11:25 INFO - 'install', 18:11:25 INFO - 'setup-mitmproxy', 18:11:25 INFO - 'run-tests'), 18:11:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:25 INFO - 'disable_ccov_upload': False, 18:11:25 INFO - 'download_minidump_stackwalk': True, 18:11:25 INFO - 'download_symbols': 'ondemand', 18:11:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:11:25 INFO - 'tooltool.py': '/tools/tooltool.py', 18:11:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:11:25 INFO - '/tools/misc-python/virtualenv.py')}, 18:11:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:25 INFO - 'gecko_profile': False, 18:11:25 INFO - 'gecko_profile_interval': 0, 18:11:25 INFO - 'installer_path': 'installer.exe', 18:11:25 INFO - 'log_level': 'info', 18:11:25 INFO - 'log_name': 'talos', 18:11:25 INFO - 'log_to_console': True, 18:11:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:11:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:11:25 INFO - 'opt_config_files': (), 18:11:25 INFO - 'pip_index': False, 18:11:25 INFO - 'suite': 'dromaeojs-e10s', 18:11:25 INFO - 'system_bits': '32', 18:11:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:25 INFO - 'title': 'talos-linux64-ix-005', 18:11:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:25 INFO - 'use_talos_json': True, 18:11:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:25 INFO - 'work_dir': 'build'} 18:11:25 INFO - [mozharness: 2017-07-18 01:11:25.215953Z] Running clobber step. 18:11:25 INFO - Running pre-action listener: _resource_record_pre_action 18:11:25 INFO - Running main action method: clobber 18:11:25 INFO - rmtree: /builds/slave/test/build 18:11:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:26 INFO - Running post-action listener: _resource_record_post_action 18:11:26 INFO - [mozharness: 2017-07-18 01:11:26.774266Z] Finished clobber step (success) 18:11:26 INFO - [mozharness: 2017-07-18 01:11:26.774367Z] Running read-buildbot-config step. 18:11:26 INFO - Running pre-action listener: _resource_record_pre_action 18:11:26 INFO - Running main action method: read_buildbot_config 18:11:26 INFO - Using buildbot properties: 18:11:26 INFO - { 18:11:26 INFO - "project": "", 18:11:26 INFO - "product": "firefox", 18:11:26 INFO - "who": "kwierso@gmail.com", 18:11:26 INFO - "installer_path": "public/build/target.tar.bz2", 18:11:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:11:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:11:26 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:11:26 INFO - "stage_platform": "linux64-stylosequential", 18:11:26 INFO - "basedir": "/builds/slave/test", 18:11:26 INFO - "buildnumber": 107, 18:11:26 INFO - "platform": "ubuntu64_hw_styloseq", 18:11:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:11:26 INFO - "slavebuilddir": "test", 18:11:26 INFO - "taskId": "BvV0Q1IfTqeHoB2F-ccVmw", 18:11:26 INFO - "branch": "mozilla-central", 18:11:26 INFO - "script_repo_revision": "production", 18:11:26 INFO - "revision": "5e73b9798464c3f7106f0161dc9a49b234f42f9c", 18:11:26 INFO - "slavename": "talos-linux64-ix-005", 18:11:26 INFO - "repo_path": "mozilla-central" 18:11:26 INFO - } 18:11:26 INFO - Finding installer, test and symbols from parent task. 18:11:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BvV0Q1IfTqeHoB2F-ccVmw'}, attempt #1 18:11:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BvV0Q1IfTqeHoB2F-ccVmw'}, attempt #1 18:11:27 INFO - Task dependencies: e_KBwBrBSNuUWab6NjyQ4Q 18:11:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q'}, attempt #1 18:11:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2 18:11:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/test_packages.json 18:11:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.crashreporter-symbols.zip 18:11:28 INFO - Running post-action listener: _resource_record_post_action 18:11:28 INFO - [mozharness: 2017-07-18 01:11:28.288410Z] Finished read-buildbot-config step (success) 18:11:28 INFO - [mozharness: 2017-07-18 01:11:28.288630Z] Running download-and-extract step. 18:11:28 INFO - Running pre-action listener: _resource_record_pre_action 18:11:28 INFO - Running main action method: download_and_extract 18:11:28 INFO - mkdir: /builds/slave/test/build/tests 18:11:28 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')]} 18:11:28 INFO - trying https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.test_packages.json 18:11:28 INFO - Downloading https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:11:28 INFO - Downloaded 989 bytes. 18:11:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:11:28 INFO - Using the following test package requirements: 18:11:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:11:28 INFO - u'common': [u'target.common.tests.zip'], 18:11:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:11:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:11:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:11:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:11:28 INFO - u'mozbase': [u'target.common.tests.zip'], 18:11:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:11:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:11:28 INFO - u'web-platform': [u'target.common.tests.zip', 18:11:28 INFO - u'target.web-platform.tests.tar.gz'], 18:11:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:11:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:11:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.common.tests.zip 18:11:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:11:28 INFO - Fetch https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.common.tests.zip into memory 18:11:30 INFO - Content-Length response header: 72769394 18:11:30 INFO - Bytes received: 72769394 18:11:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:11:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.common.tests.zip 18:11:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:11:41 INFO - Fetch https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.common.tests.zip into memory 18:11:43 INFO - Content-Length response header: 72769394 18:11:43 INFO - Bytes received: 72769394 18:11:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.talos.tests.zip 18:11:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:11:55 INFO - Fetch https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.talos.tests.zip into memory 18:11:56 INFO - Content-Length response header: 14495033 18:11:56 INFO - Bytes received: 14495033 18:11:56 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')]} 18:11:56 INFO - trying https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2 18:11:56 INFO - Downloading https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:11:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:11:58 INFO - Downloaded 61925351 bytes. 18:11:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2 18:11:58 INFO - mkdir: /builds/slave/test/properties 18:11:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:58 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:58 INFO - Contents: 18:11:58 INFO - build_url:https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2 18:11:58 INFO - Running post-action listener: _resource_record_post_action 18:11:58 INFO - Running post-action listener: set_extra_try_arguments 18:11:58 INFO - [mozharness: 2017-07-18 01:11:58.410971Z] Finished download-and-extract step (success) 18:11:58 INFO - [mozharness: 2017-07-18 01:11:58.411107Z] Running populate-webroot step. 18:11:58 INFO - Running pre-action listener: _resource_record_pre_action 18:11:58 INFO - Running main action method: populate_webroot 18:11:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:58 INFO - '--tppagecycles', 18:11:58 INFO - '7'], 18:11:58 INFO - 'tests': ['tsvgm']}}, 18:11:58 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:11:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:11:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:11:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['damp', 'tps']}, 18:11:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'tests': ['damp', 'tps']}, 18:11:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['dromaeo_dom']}, 18:11:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:11:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:11:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:11:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['a11yr', 18:11:58 INFO - 'ts_paint', 18:11:58 INFO - 'tpaint', 18:11:58 INFO - 'sessionrestore', 18:11:58 INFO - 'sessionrestore_no_auto_restore', 18:11:58 INFO - 'tabpaint']}, 18:11:58 INFO - 'other-e10s': {'tests': ['a11yr', 18:11:58 INFO - 'ts_paint', 18:11:58 INFO - 'tpaint', 18:11:58 INFO - 'sessionrestore', 18:11:58 INFO - 'sessionrestore_many_windows', 18:11:58 INFO - 'sessionrestore_no_auto_restore', 18:11:58 INFO - 'tabpaint']}, 18:11:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:11:58 INFO - 'ts_paint', 18:11:58 INFO - 'tpaint', 18:11:58 INFO - 'sessionrestore', 18:11:58 INFO - 'sessionrestore_many_windows', 18:11:58 INFO - 'sessionrestore_no_auto_restore', 18:11:58 INFO - 'tabpaint']}, 18:11:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:11:58 INFO - 'ts_paint', 18:11:58 INFO - 'tpaint', 18:11:58 INFO - 'sessionrestore', 18:11:58 INFO - 'sessionrestore_many_windows', 18:11:58 INFO - 'sessionrestore_no_auto_restore', 18:11:58 INFO - 'tabpaint']}, 18:11:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['a11yr', 18:11:58 INFO - 'ts_paint', 18:11:58 INFO - 'tpaint', 18:11:58 INFO - 'sessionrestore', 18:11:58 INFO - 'sessionrestore_no_auto_restore', 18:11:58 INFO - 'tabpaint']}, 18:11:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['a11yr', 18:11:58 INFO - 'ts_paint', 18:11:58 INFO - 'tpaint', 18:11:58 INFO - 'sessionrestore', 18:11:58 INFO - 'sessionrestore_no_auto_restore', 18:11:58 INFO - 'tabpaint']}, 18:11:58 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 18:11:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:11:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:11:58 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:11:58 INFO - 'talos_options': ['--mitmproxy', 18:11:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:11:58 INFO - '--firstNonBlankPaint'], 18:11:58 INFO - 'tests': ['quantum_pageload_google', 18:11:58 INFO - 'quantum_pageload_youtube', 18:11:58 INFO - 'quantum_pageload_amazon', 18:11:58 INFO - 'quantum_pageload_facebook']}, 18:11:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['tsvgx', 18:11:58 INFO - 'tsvgr_opacity', 18:11:58 INFO - 'tart', 18:11:58 INFO - 'tscrollx', 18:11:58 INFO - 'cart', 18:11:58 INFO - 'tsvg_static']}, 18:11:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:11:58 INFO - 'tsvgr_opacity', 18:11:58 INFO - 'tart', 18:11:58 INFO - 'tscrollx', 18:11:58 INFO - 'cart', 18:11:58 INFO - 'tsvg_static']}, 18:11:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'talos_options': ['--disable-e10s'], 18:11:58 INFO - 'tests': ['tp5o']}, 18:11:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:11:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'talos_options': ['--disable-e10s', 18:11:58 INFO - '--xperf_path', 18:11:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:58 INFO - 'tests': ['tp5n']}, 18:11:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:11:58 INFO - 'talos_options': ['--xperf_path', 18:11:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:58 INFO - 'tests': ['tp5n']}}, 18:11:58 INFO - 'talos.zip': {'path': '', 18:11:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:58 INFO - Running post-action listener: _resource_record_post_action 18:11:58 INFO - [mozharness: 2017-07-18 01:11:58.427579Z] Finished populate-webroot step (success) 18:11:58 INFO - [mozharness: 2017-07-18 01:11:58.427670Z] Running create-virtualenv step. 18:11:58 INFO - Running pre-action listener: _resource_record_pre_action 18:11:58 INFO - Running main action method: create_virtualenv 18:11:58 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:58 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 18:11:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:11:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:58 INFO - Using real prefix '/usr' 18:11:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:00 INFO - Installing pip.................done. 18:12:01 INFO - Return code: 0 18:12:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:12:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:12:01 INFO - Reading from file tmpfile_stdout 18:12:01 INFO - Output received: 18:12:01 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) 18:12:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12:01 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')]} 18:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:01 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')]} 18:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:01 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:01 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 18:12:01 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 18:12:01 INFO - Using env: {'DISPLAY': ':0', 18:12:01 INFO - 'HOME': '/home/cltbld', 18:12:01 INFO - 'LANG': 'en_US.UTF-8', 18:12:01 INFO - 'LANGUAGE': 'en_US:en', 18:12:01 INFO - 'LOGNAME': 'cltbld', 18:12:01 INFO - 'MAIL': '/var/mail/cltbld', 18:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:01 INFO - 'MOZ_NO_REMOTE': '1', 18:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:01 INFO - 'NO_EM_RESTART': '1', 18:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:01 INFO - 'PWD': '/builds/slave/test', 18:12:01 INFO - 'SHELL': '/bin/bash', 18:12:01 INFO - 'SHLVL': '1', 18:12:01 INFO - 'TERM': 'linux', 18:12:01 INFO - 'TMOUT': '86400', 18:12:01 INFO - 'USER': 'cltbld', 18:12:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:01 INFO - '_': '/tools/buildbot/bin/python'} 18:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:01 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 18:12:01 INFO - Cleaning up... 18:12:01 INFO - Return code: 0 18:12:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12:01 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')]} 18:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:01 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')]} 18:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:01 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:01 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 18:12:01 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 18:12:01 INFO - Using env: {'DISPLAY': ':0', 18:12:01 INFO - 'HOME': '/home/cltbld', 18:12:01 INFO - 'LANG': 'en_US.UTF-8', 18:12:01 INFO - 'LANGUAGE': 'en_US:en', 18:12:01 INFO - 'LOGNAME': 'cltbld', 18:12:01 INFO - 'MAIL': '/var/mail/cltbld', 18:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:01 INFO - 'MOZ_NO_REMOTE': '1', 18:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:01 INFO - 'NO_EM_RESTART': '1', 18:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:01 INFO - 'PWD': '/builds/slave/test', 18:12:01 INFO - 'SHELL': '/bin/bash', 18:12:01 INFO - 'SHLVL': '1', 18:12:01 INFO - 'TERM': 'linux', 18:12:01 INFO - 'TMOUT': '86400', 18:12:01 INFO - 'USER': 'cltbld', 18:12:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:01 INFO - '_': '/tools/buildbot/bin/python'} 18:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:01 INFO - Downloading/unpacking psutil>=3.1.1 18:12:01 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 18:12:01 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 18:12:01 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 18:12:01 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 18:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:02 INFO - Installing collected packages: psutil 18:12:02 INFO - Running setup.py install for psutil 18:12:02 INFO - building 'psutil._psutil_linux' extension 18:12:02 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 18:12:03 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 18:12:03 INFO - building 'psutil._psutil_posix' extension 18:12:03 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 18:12:03 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 18:12:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:03 INFO - Successfully installed psutil 18:12:03 INFO - Cleaning up... 18:12:03 INFO - Return code: 0 18:12:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:03 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:03 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 18:12:03 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 18:12:03 INFO - Using env: {'DISPLAY': ':0', 18:12:03 INFO - 'HOME': '/home/cltbld', 18:12:03 INFO - 'LANG': 'en_US.UTF-8', 18:12:03 INFO - 'LANGUAGE': 'en_US:en', 18:12:03 INFO - 'LOGNAME': 'cltbld', 18:12:03 INFO - 'MAIL': '/var/mail/cltbld', 18:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:03 INFO - 'MOZ_NO_REMOTE': '1', 18:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:03 INFO - 'NO_EM_RESTART': '1', 18:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:03 INFO - 'PWD': '/builds/slave/test', 18:12:03 INFO - 'SHELL': '/bin/bash', 18:12:03 INFO - 'SHLVL': '1', 18:12:03 INFO - 'TERM': 'linux', 18:12:03 INFO - 'TMOUT': '86400', 18:12:03 INFO - 'USER': 'cltbld', 18:12:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:03 INFO - '_': '/tools/buildbot/bin/python'} 18:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:03 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 18:12:03 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 18:12:03 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 18:12:03 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 18:12:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:04 INFO - Installing collected packages: mozsystemmonitor 18:12:04 INFO - Running setup.py install for mozsystemmonitor 18:12:04 INFO - Successfully installed mozsystemmonitor 18:12:04 INFO - Cleaning up... 18:12:04 INFO - Return code: 0 18:12:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:04 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')]} 18:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:04 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')]} 18:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:04 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:04 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 18:12:04 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 18:12:04 INFO - Using env: {'DISPLAY': ':0', 18:12:04 INFO - 'HOME': '/home/cltbld', 18:12:04 INFO - 'LANG': 'en_US.UTF-8', 18:12:04 INFO - 'LANGUAGE': 'en_US:en', 18:12:04 INFO - 'LOGNAME': 'cltbld', 18:12:04 INFO - 'MAIL': '/var/mail/cltbld', 18:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:04 INFO - 'MOZ_NO_REMOTE': '1', 18:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:04 INFO - 'NO_EM_RESTART': '1', 18:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:04 INFO - 'PWD': '/builds/slave/test', 18:12:04 INFO - 'SHELL': '/bin/bash', 18:12:04 INFO - 'SHLVL': '1', 18:12:04 INFO - 'TERM': 'linux', 18:12:04 INFO - 'TMOUT': '86400', 18:12:04 INFO - 'USER': 'cltbld', 18:12:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:04 INFO - '_': '/tools/buildbot/bin/python'} 18:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:05 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:05 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 18:12:05 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 18:12:05 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 18:12:05 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 18:12:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:06 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 18:12:06 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 18:12:06 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 18:12:06 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 18:12:06 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:06 INFO - warning: no files found matching '*.txt' 18:12:06 INFO - no previously-included directories found matching 'build' 18:12:06 INFO - no previously-included directories found matching 'dist' 18:12:06 INFO - no previously-included directories found matching '.git*' 18:12:06 INFO - Installing collected packages: jsonschema, functools32 18:12:06 INFO - Running setup.py install for functools32 18:12:06 INFO - warning: no files found matching '*.txt' 18:12:06 INFO - no previously-included directories found matching 'build' 18:12:06 INFO - no previously-included directories found matching 'dist' 18:12:06 INFO - no previously-included directories found matching '.git*' 18:12:06 INFO - Successfully installed jsonschema functools32 18:12:06 INFO - Cleaning up... 18:12:06 INFO - Return code: 0 18:12:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:12:06 INFO - Using env: {'DISPLAY': ':0', 18:12:06 INFO - 'HOME': '/home/cltbld', 18:12:06 INFO - 'LANG': 'en_US.UTF-8', 18:12:06 INFO - 'LANGUAGE': 'en_US:en', 18:12:06 INFO - 'LOGNAME': 'cltbld', 18:12:06 INFO - 'MAIL': '/var/mail/cltbld', 18:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:06 INFO - 'MOZ_NO_REMOTE': '1', 18:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:06 INFO - 'NO_EM_RESTART': '1', 18:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:06 INFO - 'PWD': '/builds/slave/test', 18:12:06 INFO - 'SHELL': '/bin/bash', 18:12:06 INFO - 'SHLVL': '1', 18:12:06 INFO - 'TERM': 'linux', 18:12:06 INFO - 'TMOUT': '86400', 18:12:06 INFO - 'USER': 'cltbld', 18:12:06 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:06 INFO - '_': '/tools/buildbot/bin/python'} 18:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:06 INFO - Cleaning up... 18:12:06 INFO - Return code: 0 18:12:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:12:06 INFO - Using env: {'DISPLAY': ':0', 18:12:06 INFO - 'HOME': '/home/cltbld', 18:12:06 INFO - 'LANG': 'en_US.UTF-8', 18:12:06 INFO - 'LANGUAGE': 'en_US:en', 18:12:06 INFO - 'LOGNAME': 'cltbld', 18:12:06 INFO - 'MAIL': '/var/mail/cltbld', 18:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:06 INFO - 'MOZ_NO_REMOTE': '1', 18:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:06 INFO - 'NO_EM_RESTART': '1', 18:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:06 INFO - 'PWD': '/builds/slave/test', 18:12:06 INFO - 'SHELL': '/bin/bash', 18:12:06 INFO - 'SHLVL': '1', 18:12:06 INFO - 'TERM': 'linux', 18:12:06 INFO - 'TMOUT': '86400', 18:12:06 INFO - 'USER': 'cltbld', 18:12:06 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:06 INFO - '_': '/tools/buildbot/bin/python'} 18:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:07 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:08 INFO - Downloading docopt-0.6.1.tar.gz 18:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:08 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:08 INFO - Running setup.py install for blobuploader 18:12:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:09 INFO - Running setup.py install for requests 18:12:09 INFO - Running setup.py install for docopt 18:12:09 INFO - Successfully installed blobuploader requests docopt 18:12:09 INFO - Cleaning up... 18:12:09 INFO - Return code: 0 18:12:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:09 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')]} 18:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:09 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')]} 18:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:09 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:09 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 18:12:09 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 18:12:09 INFO - Using env: {'DISPLAY': ':0', 18:12:09 INFO - 'HOME': '/home/cltbld', 18:12:09 INFO - 'LANG': 'en_US.UTF-8', 18:12:09 INFO - 'LANGUAGE': 'en_US:en', 18:12:09 INFO - 'LOGNAME': 'cltbld', 18:12:09 INFO - 'MAIL': '/var/mail/cltbld', 18:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:09 INFO - 'MOZ_NO_REMOTE': '1', 18:12:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:09 INFO - 'NO_EM_RESTART': '1', 18:12:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:09 INFO - 'PWD': '/builds/slave/test', 18:12:09 INFO - 'SHELL': '/bin/bash', 18:12:09 INFO - 'SHLVL': '1', 18:12:09 INFO - 'TERM': 'linux', 18:12:09 INFO - 'TMOUT': '86400', 18:12:09 INFO - 'USER': 'cltbld', 18:12:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:09 INFO - '_': '/tools/buildbot/bin/python'} 18:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:09 INFO - Running setup.py (path:/tmp/pip-HSlh50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:09 INFO - Running setup.py (path:/tmp/pip-2xDaUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:09 INFO - Running setup.py (path:/tmp/pip-nWqaSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:09 INFO - Running setup.py (path:/tmp/pip-bMWLji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:10 INFO - Running setup.py (path:/tmp/pip-FUZMns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:10 INFO - Running setup.py (path:/tmp/pip-_M015p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:10 INFO - Running setup.py (path:/tmp/pip-gogXef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:10 INFO - Running setup.py (path:/tmp/pip-Jbdnnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:10 INFO - Running setup.py (path:/tmp/pip-cL3uMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:10 INFO - Running setup.py (path:/tmp/pip-nK4YYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:10 INFO - Running setup.py (path:/tmp/pip-3wxERd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:10 INFO - Running setup.py (path:/tmp/pip-vN1VSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:10 INFO - Running setup.py (path:/tmp/pip-EQ5yuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:10 INFO - Running setup.py (path:/tmp/pip-DqWmTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:10 INFO - Running setup.py (path:/tmp/pip-KvF3yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:10 INFO - Running setup.py (path:/tmp/pip-XtaTle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:10 INFO - Running setup.py (path:/tmp/pip-EUHr7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:10 INFO - Running setup.py install for manifestparser 18:12:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozcrash 18:12:11 INFO - Running setup.py install for mozdebug 18:12:11 INFO - Running setup.py install for mozdevice 18:12:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozfile 18:12:11 INFO - Running setup.py install for mozhttpd 18:12:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozinfo 18:12:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozInstall 18:12:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozleak 18:12:11 INFO - Running setup.py install for mozlog 18:12:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for moznetwork 18:12:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:12 INFO - Running setup.py install for mozprocess 18:12:12 INFO - Running setup.py install for mozprofile 18:12:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:12 INFO - Running setup.py install for mozrunner 18:12:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:12 INFO - Running setup.py install for mozscreenshot 18:12:12 INFO - Running setup.py install for moztest 18:12:12 INFO - Running setup.py install for mozversion 18:12:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:12 INFO - Cleaning up... 18:12:12 INFO - Return code: 0 18:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:12 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:12 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 18:12:12 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 18:12:12 INFO - Using env: {'DISPLAY': ':0', 18:12:12 INFO - 'HOME': '/home/cltbld', 18:12:12 INFO - 'LANG': 'en_US.UTF-8', 18:12:12 INFO - 'LANGUAGE': 'en_US:en', 18:12:12 INFO - 'LOGNAME': 'cltbld', 18:12:12 INFO - 'MAIL': '/var/mail/cltbld', 18:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:12 INFO - 'MOZ_NO_REMOTE': '1', 18:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:12 INFO - 'NO_EM_RESTART': '1', 18:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:12 INFO - 'PWD': '/builds/slave/test', 18:12:12 INFO - 'SHELL': '/bin/bash', 18:12:12 INFO - 'SHLVL': '1', 18:12:12 INFO - 'TERM': 'linux', 18:12:12 INFO - 'TMOUT': '86400', 18:12:12 INFO - 'USER': 'cltbld', 18:12:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:12 INFO - '_': '/tools/buildbot/bin/python'} 18:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:12 INFO - Running setup.py (path:/tmp/pip-ucpSzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:13 INFO - Running setup.py (path:/tmp/pip-l9XA6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:13 INFO - Running setup.py (path:/tmp/pip-EFZbYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:13 INFO - Running setup.py (path:/tmp/pip-UKcnMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:13 INFO - Running setup.py (path:/tmp/pip-69HpYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:13 INFO - Running setup.py (path:/tmp/pip-U7Rlcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:13 INFO - Running setup.py (path:/tmp/pip-yponlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:13 INFO - Running setup.py (path:/tmp/pip-AWOLWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:13 INFO - Running setup.py (path:/tmp/pip-YUCKt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:13 INFO - Running setup.py (path:/tmp/pip-56AKYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:13 INFO - Running setup.py (path:/tmp/pip-w60_pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:13 INFO - Running setup.py (path:/tmp/pip-c9315A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:13 INFO - Running setup.py (path:/tmp/pip-QbXKWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:13 INFO - Running setup.py (path:/tmp/pip-UcY1Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:13 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)) 18:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:13 INFO - Running setup.py (path:/tmp/pip-q9IgUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:14 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)) 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:14 INFO - Running setup.py (path:/tmp/pip-FxwuUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:14 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)) 18:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:14 INFO - Running setup.py (path:/tmp/pip-xpqA4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:14 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)) 18:12:14 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)) 18:12:14 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)) 18:12:14 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)) 18:12:14 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)) 18:12:14 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)) 18:12:14 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)) 18:12:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 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 18:12:15 INFO - Downloading blessings-1.6.tar.gz 18:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:15 INFO - Installing collected packages: blessings 18:12:15 INFO - Running setup.py install for blessings 18:12:15 INFO - Successfully installed blessings 18:12:15 INFO - Cleaning up... 18:12:15 INFO - Return code: 0 18:12:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:15 INFO - Reading from file tmpfile_stdout 18:12:15 INFO - Current package versions: 18:12:15 INFO - argparse == 1.2.1 18:12:15 INFO - blessings == 1.6 18:12:15 INFO - blobuploader == 1.2.4 18:12:15 INFO - docopt == 0.6.1 18:12:15 INFO - functools32 == 3.2.3-2 18:12:15 INFO - jsonschema == 2.5.1 18:12:15 INFO - manifestparser == 1.1 18:12:15 INFO - mozInstall == 1.13 18:12:15 INFO - mozcrash == 1.0 18:12:15 INFO - mozdebug == 0.1 18:12:15 INFO - mozdevice == 0.51 18:12:15 INFO - mozfile == 1.2 18:12:15 INFO - mozhttpd == 0.7 18:12:15 INFO - mozinfo == 0.10 18:12:15 INFO - mozleak == 0.1 18:12:15 INFO - mozlog == 3.5 18:12:15 INFO - moznetwork == 0.27 18:12:15 INFO - mozprocess == 0.25 18:12:15 INFO - mozprofile == 0.28 18:12:15 INFO - mozrunner == 6.13 18:12:15 INFO - mozscreenshot == 0.1 18:12:15 INFO - mozsystemmonitor == 0.3 18:12:15 INFO - moztest == 0.8 18:12:15 INFO - mozversion == 1.4 18:12:15 INFO - psutil == 3.1.1 18:12:15 INFO - requests == 1.2.3 18:12:15 INFO - wsgiref == 0.1.2 18:12:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12: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')]} 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:15 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:15 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 18:12:15 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 18:12:15 INFO - Using env: {'DISPLAY': ':0', 18:12:15 INFO - 'HOME': '/home/cltbld', 18:12:15 INFO - 'LANG': 'en_US.UTF-8', 18:12:15 INFO - 'LANGUAGE': 'en_US:en', 18:12:15 INFO - 'LOGNAME': 'cltbld', 18:12:15 INFO - 'MAIL': '/var/mail/cltbld', 18:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:15 INFO - 'MOZ_NO_REMOTE': '1', 18:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:15 INFO - 'NO_EM_RESTART': '1', 18:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:15 INFO - 'PWD': '/builds/slave/test', 18:12:15 INFO - 'SHELL': '/bin/bash', 18:12:15 INFO - 'SHLVL': '1', 18:12:15 INFO - 'TERM': 'linux', 18:12:15 INFO - 'TMOUT': '86400', 18:12:15 INFO - 'USER': 'cltbld', 18:12:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:15 INFO - '_': '/tools/buildbot/bin/python'} 18:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:17 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:12:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:12:17 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 18:12:17 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 18:12:17 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 18:12:17 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 18:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:12:17 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)) 18:12:17 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)) 18:12:17 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)) 18:12:17 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)) 18:12:17 INFO - Installing collected packages: marionette-driver, simplejson 18:12:17 INFO - Running setup.py install for marionette-driver 18:12:17 INFO - Running setup.py install for simplejson 18:12:17 INFO - building 'simplejson._speedups' extension 18:12:17 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 18:12:18 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 18:12:18 INFO - Successfully installed marionette-driver simplejson 18:12:18 INFO - Cleaning up... 18:12:18 INFO - Return code: 0 18:12:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:18 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 0x17ad1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', '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 18:12:18 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 18:12:18 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 18:12:18 INFO - Using env: {'DISPLAY': ':0', 18:12:18 INFO - 'HOME': '/home/cltbld', 18:12:18 INFO - 'LANG': 'en_US.UTF-8', 18:12:18 INFO - 'LANGUAGE': 'en_US:en', 18:12:18 INFO - 'LOGNAME': 'cltbld', 18:12:18 INFO - 'MAIL': '/var/mail/cltbld', 18:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:18 INFO - 'MOZ_NO_REMOTE': '1', 18:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:18 INFO - 'NO_EM_RESTART': '1', 18:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:18 INFO - 'PWD': '/builds/slave/test', 18:12:18 INFO - 'SHELL': '/bin/bash', 18:12:18 INFO - 'SHLVL': '1', 18:12:18 INFO - 'TERM': 'linux', 18:12:18 INFO - 'TMOUT': '86400', 18:12:18 INFO - 'USER': 'cltbld', 18:12:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:18 INFO - '_': '/tools/buildbot/bin/python'} 18:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:18 INFO - Cleaning up... 18:12:18 INFO - Return code: 0 18:12:18 INFO - Running post-action listener: _resource_record_post_action 18:12:18 INFO - Running post-action listener: _start_resource_monitoring 18:12:18 INFO - Starting resource monitoring. 18:12:18 INFO - [mozharness: 2017-07-18 01:12:18.756488Z] Finished create-virtualenv step (success) 18:12:18 INFO - [mozharness: 2017-07-18 01:12:18.756958Z] Running install step. 18:12:18 INFO - Running pre-action listener: _resource_record_pre_action 18:12:18 INFO - Running main action method: install 18:12:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:18 INFO - Reading from file tmpfile_stdout 18:12:18 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:19 INFO - Reading from file tmpfile_stdout 18:12:19 INFO - Output received: 18:12:19 INFO - Usage: mozinstall [options] installer 18:12:19 INFO - Options: 18:12:19 INFO - -h, --help show this help message and exit 18:12:19 INFO - -d DEST, --destination=DEST 18:12:19 INFO - Directory to install application into. [default: 18:12:19 INFO - "/builds/slave/test"] 18:12:19 INFO - --app=APP Application being installed. [default: firefox] 18:12:19 INFO - mkdir: /builds/slave/test/build/application 18:12:19 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'] 18:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:12:37 INFO - Reading from file tmpfile_stdout 18:12:37 INFO - Output received: 18:12:37 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:37 INFO - Running post-action listener: _resource_record_post_action 18:12:37 INFO - [mozharness: 2017-07-18 01:12:37.033208Z] Finished install step (success) 18:12:37 INFO - [mozharness: 2017-07-18 01:12:37.033463Z] Running setup-mitmproxy step. 18:12:37 INFO - Running pre-action listener: _resource_record_pre_action 18:12:37 INFO - Running main action method: setup_mitmproxy 18:12:37 INFO - Skipping: mitmproxy is not required 18:12:37 INFO - Running post-action listener: _resource_record_post_action 18:12:37 INFO - [mozharness: 2017-07-18 01:12:37.034606Z] Finished setup-mitmproxy step (success) 18:12:37 INFO - [mozharness: 2017-07-18 01:12:37.034939Z] Running run-tests step. 18:12:37 INFO - Running pre-action listener: _resource_record_pre_action 18:12:37 INFO - Running pre-action listener: _set_gcov_prefix 18:12:37 INFO - Running main action method: run_tests 18:12:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:37 INFO - Python 2.7.3 18:12:37 INFO - Return code: 0 18:12:37 INFO - grabbing minidump binary from tooltool 18:12:37 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')]} 18:12:37 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 0x1b1bd00>, '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 0x1b1cc00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b1d090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:37 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 18:12:37 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 18:12:37 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 18:12:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:37 INFO - Return code: 0 18:12:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:37 INFO - ENV: RUST_BACKTRACE is now 1 18:12:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:37 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/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:12:37 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/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:12:37 INFO - Using env: {'DISPLAY': ':0', 18:12:37 INFO - 'HOME': '/home/cltbld', 18:12:37 INFO - 'LANG': 'en_US.UTF-8', 18:12:37 INFO - 'LANGUAGE': 'en_US:en', 18:12:37 INFO - 'LOGNAME': 'cltbld', 18:12:37 INFO - 'MAIL': '/var/mail/cltbld', 18:12:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:37 INFO - 'MOZ_NO_REMOTE': '1', 18:12:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:37 INFO - 'NO_EM_RESTART': '1', 18:12:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:37 INFO - 'PWD': '/builds/slave/test', 18:12:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:37 INFO - 'RUST_BACKTRACE': '1', 18:12:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:12:37 INFO - 'SHELL': '/bin/bash', 18:12:37 INFO - 'SHLVL': '1', 18:12:37 INFO - 'TERM': 'linux', 18:12:37 INFO - 'TMOUT': '86400', 18:12:37 INFO - 'USER': 'cltbld', 18:12:37 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404', 18:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:37 INFO - '_': '/tools/buildbot/bin/python'} 18:12:37 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/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:12:37 INFO - mozversion application_buildid: 20170718002759 18:12:37 INFO - mozversion application_changeset: 5e73b9798464c3f7106f0161dc9a49b234f42f9c 18:12:37 INFO - mozversion application_display_name: Nightly 18:12:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:37 INFO - mozversion application_name: Firefox 18:12:37 INFO - mozversion application_remotingname: firefox 18:12:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:12:37 INFO - mozversion application_vendor: Mozilla 18:12:37 INFO - mozversion application_version: 56.0a1 18:12:37 INFO - mozversion platform_buildid: 20170718002759 18:12:37 INFO - mozversion platform_changeset: 5e73b9798464c3f7106f0161dc9a49b234f42f9c 18:12:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:12:37 INFO - mozversion platform_version: 56.0a1 18:12:37 INFO - using testdate: 1500340357 18:12:37 INFO - actual date: 1500340357 18:12:37 INFO - starting webserver on 'localhost:39989' 18:12:37 INFO - SUITE-START | Running 2 tests 18:12:37 INFO - TEST-START | dromaeo_css 18:12:37 INFO - Initialising browser for dromaeo_css test... 18:12:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39989/getInfo.html -profile /tmp/tmpdyXKBH/profile 18:12:37 INFO - TEST-INFO | started process 11367 (/builds/slave/test/build/application/firefox/firefox http://localhost:39989/getInfo.html) 18:12:43 INFO - TEST-INFO | 11367: exit 0 18:12:43 INFO - Browser initialized. 18:12:43 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:43 INFO - TEST-INFO | started process 11593 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdyXKBH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:55 INFO - PID 11593 | RSS: Main: 170299392 18:12:55 INFO - PID 11593 | 18:15:53 INFO - PID 11593 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39989/tests/dromaeo/cssquery-ext.html) 18:15:55 INFO - PID 11593 | RSS: Main: 178593792 18:15:55 INFO - PID 11593 | 18:18:56 INFO - PID 11593 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-ext.html (next: http://localhost:39989/tests/dromaeo/cssquery-jquery.html) 18:18:57 INFO - PID 11593 | RSS: Main: 175886336 18:18:57 INFO - PID 11593 | 18:21:59 INFO - PID 11593 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39989/tests/dromaeo/cssquery-mootools.html) 18:21:59 INFO - PID 11593 | RSS: Main: 176545792 18:21:59 INFO - PID 11593 | 18:25:01 INFO - PID 11593 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39989/tests/dromaeo/cssquery-prototype.html) 18:25:01 INFO - PID 11593 | RSS: Main: 177086464 18:25:01 INFO - PID 11593 | 18:28:05 INFO - PID 11593 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39989/tests/dromaeo/cssquery-yui.html) 18:28:05 INFO - PID 11593 | RSS: Main: 176459776 18:28:05 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | Cycle 1(1): loaded http://localhost:39989/tests/dromaeo/cssquery-yui.html (next: http://localhost:39989/tests/dromaeo/cssquery-dojo.html) 18:31:07 INFO - PID 11593 | RSS: Main: 180346880 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | __start_tp_report 18:31:07 INFO - PID 11593 | _x_x_mozilla_page_load 18:31:07 INFO - PID 11593 | _x_x_mozilla_page_load_details 18:31:07 INFO - PID 11593 | |i|pagename|runs| 18:31:07 INFO - PID 11593 | |0;dojo.html;3053.9083827485183;3088.752899768019;3091.7951585843257;3106.301082256491;3115.112193024987;5987.461128498434;6036.5774395792205;6069.78755743549;6086.939130608693;6116.908246376308;6074.392560743923;6128.172696685946;6136;6144.631322120665;6154.753809847601;5564.276644036269;5570.832875013756;5612.859678508029;5620.325560932693;5623.550115990711;5572.192032155335;5596.972015139919;5613.073842815951;5618.8595285118;5633.887322253573;5601.887962240753;5602.831915042555;5621.072523033715;5621.184928185411;5639.746211420475;5573.554115670757;5610.747516361774;5615.1857980592995;5625.971870140664;5630.943690563124;5539.892021595697;5550.944490555084;5563.554915606742;5570.387257401723;5659.547236221134;5958.433948774859;6022.909656355158;6077.726502307407;6077.848053798646;6128.724207410677;6464.967675161641;6503.15458990333;6531.477481801445;6543.1821022372205;6563.507736919702;6365.490760739176;6469.547131700783;6470.2559205691;6480.546361754679;6506.739730410778;6008.188894499231;6022.488088512482;6046.727897244635;6072.544559158081;6077.270727512682;6696.196456425211;6723.193216813916;6745.123133992648;6759;6843.863122737517;6335.144755458046;6398.520110991694;6402.551821372459;6484.1894763154605;6508;6479.773207937794;6513.934860651428;6555.868882622321;6592.736290548421;6596.373344532261;6369.5541312108635;6440.935590644033;6455.548111632234;6461.19235095613;6478.02829575558;6467.417932386108;6524.086627872101;6544.214694236722;6546.869062618816;6625.86748265032;5297.549708274762;5318.22885681574;5361.919571206435;5378.462153784669;5382.946170538323;4439.134368798053;4508.819647214074;4520.299353600197;4526.4115664964;4528.117017181618;5653.943460565424;5660.037793575102;5660.575456840754;5666.376698563155;5674.092145256739;6543.640099794462;6546.345365463416;6546.476281897437;6553.2136143663065;6559.639219842859;5564.054110801174;5595.356533998561;5653.943460565424;5661.943380566224;5673.262475878109;5728.140778883201;5759.107338362561;5775.104858746901;5780.913286300709;5806.042003069446;5343.946560534345;6329.3354197809485;6343.207099112611;6349.460295874902;6390.552661313756;6484.902726459107;6493.22081350241;6501.837454063686;6599.868002639824;6619.139511863427;6297.55917085818;6304.747810087545;6324.430801227728;6389.808305750835;6401.231852177769;6311.526635502264;6404.135441715309;6427.0680751289865;6453.128827608213;6511.609303441809;6237.313895471585;6550.868982620225;6620.735170593315;6636.535442518977;6639.070530125883;6338.524610654127;6345.619262844244;6403.039544068427;6405.807825765234;6407.967960160356;6325.525585581007;6335.461485773577;6378.170837791243;6421.486281097595;6429.58207716497;6182.969085154546;6205.22434695663;6209.099680546386;6313.715882785194;6314.715837787402;6333.873322533432;6336.873262534816;6337.112804207322;6380.840478988062;6403.135576697273;6097.420745029215;6195;6205.81382558524;6244.063390491463;6281.089242059968 18:31:07 INFO - PID 11593 | |1;ext.html;771.2942657468417;808.7978005498626;834.2491757418322;853.4665833853842;854.756394427588;39261.60738392619;39890;39940.80029599852;40002.59997400025;40076.59923400765;36014.09964750876;36334.63665363345;36387.454188187134;36512.45231321525;36572.81713591435;6732.764353247638;6815.045893574904;6819.24988251292;6821.45428365732;6856.760013399532;6699.799006029827;6770.627615481147;6799.116114905056;6818.9659051704675;6850.7602233921825;6766.424853887425;6775.186977562699;6786.151731033621;6803.761868334609;6804.21751498579;6626.8674626507445;6690.096836927026;6697.5646582972195;6738.898916516257;6747.392734653905;6002.069679199731;6091.817245482643;6110.022396416596;6111.2055432793895;6116.571839971204;32199;32344.676553234403;32357;32515.187120321945;32572.348553028805;3379.2734562069027;3413.2320227948762;3415.9658403415897;3524.101354154674;3637;30255.697443025507;30470.390592188254;30589;30610;30632.54051189234;4783.7129772213775;4851.975740121276;4885.36490256268;4894.975525122387;4979.701217926936;174122;174262.99999999872;176071;189487.05256473768;190067;41211.1757764849;46077.7696111524;46653.30020049702;46943.06113877772;47010.76494617505;49310;49405.75297123563;49439.752801235765;49511;49608.50391496039;16173.757393639105;16292.918535407483;16326.428574999818;16331.59171020734;16342.918285408497;13571.864281357257;13574.796378054338;13593.59219223425;13595.932020340033;13605.591832245049;14339;14412.567622971328;14425.567232983027;14481.420743170365;14556.708865822624;5534.169874518774;5760.395158508296;5777.768889244468;5876.236089308448;5888.823335299948;977.8582105594792;991.2615101749243;1014.7868947520956;1029.907308342238;1036.160709825044;22414.439639009157;22661.79039625489;22773.544529109655;22929.656055159187;23420.531589368456;1072.3137192197;1090.138790355626;1115.4367044642404;1119.9160062995309;1128.5824245029391;11596.884031159749;11980.101492388107;12020;12061.758764824654;12159.96640285568;229011;230670;231442;232237;232781;51382.74308628433;51618;51703.74148129235;51717;51840.48159518432;1410.153907655419;1448.5364683301452;1465.4870795221586;1466.9779953300708;1479.9186044767425;21620.67568986435;21862.562748744618;22099;27293;27991.300217494725;48993.2651010228;49146.75426622844;49222;49306.75346623244;49539.25691114708;192115;193719;193740;194033;195607;31191.844040780557;31278.06165815029;31680.207994800316;31770.205744856565;35574.64425355817;41217.793911030254;42123.7893810529;42249.155016898876;43793.78103109464;44024.55975440205;30398.696013039586;31778.205544861565;31804;31854;32222.83888580636;30229;31068.37863242677;31196.376072478877;31328.21679458032;31334;34199.658003419645;34580.82709586436;35057.474137888465;35159.64840351564;35628.10929726777;28342;30479.847600761856;30740.385192296475;31352;31397.05808825739;22841.428964276027;23265.418364540346;23420.414489637897;23607.763922360555;23677.881610592525 18:31:07 INFO - PID 11593 | |2;jquery.html;538.6606437944099;549.1104410854416;552.7733629212024;564.184753031869;593.8871614393265;21345.146194152236;22549.436264093405;22625.660615090765;22633.321000369975;22683;24052;25192;25193.118240861575;25290.114845980395;26644.467110657824;6296.244450665927;6407.03894415839;6415.06981487684;6419.422251997319;6429.32492088331;6260.749570017217;6273.404026617487;6325.746970121189;6332.1135041094285;6342.714577843985;3706.3328600851837;3805.46723458716;6234.875302493947;6295.338989406115;6300.21247344082;6278.6860656966965;6311.084892690557;6346.30190679025;6350.587211831216;6351.555391122623;5864.149698293766;5897.439743224387;5902.996490596609;5919.408059194089;5925.34821169671;28597.428051439063;29486.410271794444;29763;29803.10590682283;29837.70162298371;1893.6591413545557;1949.4054313434397;1958.2852258925532;1962.7252184694155;1966.3412756726514;17931.820681793146;18354.541136471562;18443.538911527186;18443.815561844345;18476.907615461972;2286.5998450271304;2296.2766728480474;2341.110378056345;2357.905683772647;2380.2502211803226;120529;125893;125927.37036314851;126126.36936815258;126824.99999999908;14144.504942326968;14547.27263636814;14551.490697825526;14817;15138.167400792841;23179.768202318097;23215.767842321362;23226.883865580563;23240.883795581252;23282.883585581963;10443;10588.311759735592;10704.839427408595;10758.462076896123;10869.782604348027;9874.901250987541;9967.651132210338;10081.798364032678;10085.747856303504;10206.591736330613;8801.51591662452;9070.18368346852;9205.63177472907;9248.722538323862;9262.259019278441;2411.807055435596;2638.7493188147096;2703.513367593852;2722.632444619951;2769.418422131335;950.9001554836781;959.5010594490965;960.1934375124929;985.6205360936091;991.8413053911339;18643.533911652317;19319.80680193208;19582.608347832964;19741.703874441893;19874.205031798854;998.5855869814092;1036.1710631494775;1051.9684409467727;1052.2266134391216;1058.3015209961388;10618.575256989789;10682.572697092184;10777.353358798497;10890.836637450444;10958.890411095786;502353;509991;524221;526290;526560;108317;108662;109005;109465;112410;1165.5745652836822;1212.6907638552113;1220.7070303127186;1224.7121926347302;1232.4330807828499;16157.919210403872;16879.40922067722;17055.48833534996;17259.482215533553;17860.91069544644;49703.25445118254;51571.742141289054;52350.73824630852;53843;54204.99999999842;66188;66300;66596.6670166646;66601.33398665952;67056.66471667611;14021.929890350482;14243.572692819233;14252.857471425568;14304.356303966555;14304.928475357556;21475.463113421665;22266.554668906854;22275.331740047826;22488.21291254798;22768.658470123293;17143.657126857142;17631.559211019827;17807.842490238094;17880.92714437138;17899.463016109537;13816;14045.367958442099;16084.839151608334;16139.193040348171;17053.06208158563;19558.706619400436;19695.704564431257;19699.409017728918;19783.703244451634;19917;14677.339519721852;14834.035787673767;14861.182634955181;14866.182359970306;14880.702385952003;15859.682806343577;18634.254629814655;18791.530211744815;18804.435866924014;18862.811371886106 18:31:07 INFO - PID 11593 | |3;mootools.html;1346.3201083452855;1394.0450791208023;1398.8671076247754;1407.4440595964588;1413.6819215676471;13362.198268103908;14040.5085821996;14048.92975535121;14055.718885622282;14097.154170749749;13745.381457834397;14122.293885305746;14125.505607303747;14148.221847798366;14150.433982640681;2657.2958166085955;2706.3910620110414;2716.1308381317986;2724.850133242671;2735.1384313941144;2563.538563058649;2630.8816103275303;2636.7626913577774;2648.9337766555896;2652.801039922004;2466.654668346433;2521.2184222890965;2549.7450254974538;2573.9227823165334;2609.073778808524;2621.580547112462;2736.5484695025298;2738.52075886719;2742.0951086141527;2745.203890871655;2599.6880374355196;2610.4387556675406;2612.242449689607;2612.6472926155107;2612.973870261292;14000.719985600232;14119;14186.92906535471;14190.148591084568;14270.571882843427;7380.557166570023;7721;7742.8838567421535;7747.72882949094;7757.146713861527;4653.581177694024;4899.681520701143;5021.949780502221;5039.118154322979;5073.137566613685;1833.706606942896;1874.690676038452;1883.5479484923658;1951.5999220159833;1959.8138176873174;22961.77038229613;23065.423364415856;23139.76860231393;23179.3046208614;23281.883590582107;6192.380761923861;6262.436380725757;6302.684865756693;6320.273168585672;6505.763904858062;15733.134677592841;15767.842321576867;15797.21013949298;15808.051516909023;15845.603859903364;7327.890081648779;7445.218252083561;7451.515651482637;7454.813629659302;7489.925100749032;6771.6614169292325;6869.725210991604;6928.861422771516;6975.232724400312;7024.5434046786795;4731;4871.780769865293;4933.531314525115;4933.963867587775;5029.06962211992;1373.7183877305129;1376.3875075591466;1382.5368501552093;1415.0872687116957;1430.6351880270465;873.7160422862595;899.9820003599891;925.6251218256617;933.3979583168818;941.091846368258;7303.233160518175;7307.7807665770115;7323.487355885143;8691.217790398894;8749.47503149813;983.8671779309844;996.1731762636994;1022.6880801355509;1030.7783826477344;1034.591336422109;5087.491250874957;5177.330822749083;5192.247124166975;5284.550813180846;5457.345118585716;18251.72622410665;19245.230190792492;19300;19339.323123690603;19362.8063719361;17606.38377656776;17729.37947171843;17797.99999999974;17945;17973.640527189382;9756.804863902682;10128.088472037552;10497.685069447776;10545.84181237282;10724.356538607708;7964.840703185788;8172.7956801080445;8236.135205803545;8329.541875196923;8635.395522313378;15377.231138443256;16378.918105409397;16393.754093688844;16648.916755416147;16739.497815065566;16264.51206463808;16387.83612163911;16666.749998749787;16712.916435418232;16981.915090424467;10970.177236707119;11429.142814288794;11463.598774042868;11502.424878756196;11551.942240288745;13087.934560327138;13167.341632918507;13224.47102115943;13280.667983300495;13281.402336895057;9840.557174927002;10109.292349535463;10191.796164076824;10294.897051029393;10340.121089707158;10134.391936483835;10328.638497652546;10707.57169713234;10848.783024339627;10893.836592450962;8965.417247878868;8986;9118.908810912071;9169;9233.953830230806;6864.107666003388;6929.376356127771;6959.269276726073;7007.229204787572;7073.71705131789;6831.760888368884;6918.654067296515;6944.4097251734165;6951.8609627806145;7038.120234970629 18:31:07 INFO - PID 11593 | |4;prototype.html;480.94469136049366;481.8506263058453;485.40053034502387;517.4799326676695;518.2511271213097;6115.969420152904;6117.6329420234815;6119.9596068668325;6185.690715464231;6190.1952746143;5829.163813398787;5951;6040.516758659308;6063.514918806496;6081.270247570298;4894.706317620937;4940.2342636891335;4942.481039490855;4974.37820272466;4985.451600323961;4820.734859582722;4895.853124406273;4912.803487860481;4937.382827146606;4953.851384458472;4849.933014736752;4868.586170175539;4900.6569540132205;4901.362822833027;4930.778114984816;4669.883252918704;4810;4818.952080252569;4889.168841296988;4901.901961960759;4336.119173339875;4364.803583838735;4375.234333991539;4387.670924680661;4409.272470042439;5804.564657650651;5856.209411729447;5878.764849406019;5887.470127688529;5925.851853703649;5567.3040869891265;5595.972020139914;5629.211910332557;5641.182028605829;5647.830565083054;6331.14529538511;6348.77779277723;6376.617402955838;6404.871902561923;6417.117643825512;4726.480087190441;4753.2413007187215;4753.619710423158;4759.61923046159;4784.66507344486;6665.600063996128;6806.829829254259;6821.965890170567;6844.4182244509275;6851.588904665686;4929.654924155347;4980.800767969313;4983.426905905795;4992.700437973734;4998.4501704813165;4959.776810043557;5013.147764879979;5022.4977502249485;5024.773885175176;5026.421961474404;5515.283013208337;5549.112142057332;5581.776728930798;5586.273784408001;5602.6918519481005;6384.58500197495;6397.584157029778;6425.357464253537;6436.3241859605005;6531.3142120077655;1716.768236288085;1746.519707080563;1746.8427841494072;1753.5616095976006;1758.78015248094;1121.556984990924;1182.6038277177242;1197.952081916731;1202.8676845546984;1211.8909298163207;5467.0865197180665;5523.889522209533;5603.495685388253;5621.718914054362;5643.5767317451355;6576.980568011966;6587.604743715392;6603.141591593062;6617.735290588323;6627.403533681992;5627.915581266284;5676.083502383342;5722.713864306768;5755.1367294906095;5775.711214439261;3464.255185135208;3489.197484578561;3492.511048453219;3494.3186078714407;3552.715782737375;6360.074365791787;6368.993766963392;6371.681415929185;6433.228012638513;6436.35636436362;6746.696398662072;6767.424768894699;6775.559588626724;6785.59286442815;6791.728330866808;6345.555811093363;6368.75193651232;6426.550141490145;6433.163688720437;6438.613683178931;6594.142761440982;6653.467722582279;6662.100616416722;6664.83337916556;6676.866462670817;6411.839704007437;6464.644444555594;6550.6069635822005;6565.045729421684;6591.242007169045;6207.937920620736;6341.619502829661;6345.460635846005;6358.427741503287;6382.489400847828;5352.4915133062295;5360.463953604608;5373.194020896741;5412.810551630674;5419.268398766116;6514.51141164424;6581.539292249497;6616.503762217949;6624.310800745779;6673.232578253369;6390.233172019201;6411.711472983634;6433.678316084083;6444.387783160592;6450.451711604566;6377.139086223486;6427.228732552124;6436.195475565554;6501.122348482894;6535.072153714571;6277.686115694289;6314.55798094129;6370.267419246846;6386.16979792624;6405.590770030586;5982.46157845796;6320.5891617044745;6397.8720425592155;6428.6785660717715;6466.967665161644;6090.9390906090375;6147.692615369303;6208.782692605737;6221.688915554295;6275.905861411991 18:31:07 INFO - PID 11593 | |5;yui.html;913.7898283394824;939.0468674295589;939.0890835889185;943.391512474454;960.2366118935446;17317.567060823487;18153.092345382727;18155.727664085032;18261.543461413472;21139.2601258956;20598.691019634716;21208.4697882553;21318.36044918651;21475.57048859022;21538.461538461546;1031.5203430404842;1067.8398240263946;1069.4118234970774;1070.9303895246824;1077.2997551591488;1053.2364036073861;1056.550965839519;1058.4443167337133;1070.7109080548248;1071.2822408985999;1018.4856647393057;1063.2557209953025;1122.573422099601;1124.8031594470976;1126;1093.436880006797;1099.356876227404;1101.2786624760795;1105.2152971390321;1106.3859557945336;795.7055889320931;800.5156880087579;800.8318253166842;802.2098233240254;803.5259197073755;18773;19358.612827743505;19502.804971950383;19687;20419.081141348674;20305.49236269105;20652;20991.685124723143;21046.579068418698;21430.571388572298;15719.764203536955;15843.762343564857;15905.602359940978;15943.123128227942;16368.263428145761;3620.076880395509;3623.8188090595363;3635.0912271932016;3644.835982380799;3654.7258955578436;121159;122358;127583.36208318899;127873.36063319624;128633.35683321524;15049.247537623076;15944;16001.759973600405;16160.353585856434;16282.348706051625;29223.56164657532;29881.85059074734;30066;30080.097597072123;30292;8586.914130858611;8664.523451210243;8676.91323086761;8721.781955451164;8777.034526202113;3993.1414745829206;4135.503739551213;4153.293940030202;4154.335306351029;4155.833766649361;5075.923861142086;5139.460356662571;5151.9484805152215;5164.354455693038;5182.32629758129;922.958466868979;944.8771659684197;954.7326748510428;962.7256231973854;967.7822489939707;683.2620769568854;687.9312068793081;702.7961891051538;707.4552594502204;720.7909706185147;11456.599019034295;11741.647750567497;11955.105672751139;12007.939960300142;12415.379231038412;683.3576438148125;688.9862202755917;702.0452185028357;703.7396163419465;704.6864145455223;5488.286522752017;5547.834954659486;5554.0835762099605;5560.304961879765;5865.67738774363;95279.52360238154;99644;100012;102471;107859;88067.55966220128;91005.54497227471;91367.54316228509;92838;93005;73456;73703.63148184225;75722;79238.6038069806;81448.59275703703;11138.387388693376;12273.447694853574;12984.610461686165;13012.609621711365;13143.671408214488;32530.51204231891;33818.66181338155;35741.285174296885;36072;37559.248815023;64033.67983160241;64223.678881605294;64628.67685661541;66280.6685966567;69111;18988.905055474635;18992.335268265546;19170.424887253404;19189;19366.515837104183;20316.187352505734;20873.373798786062;21350.35948921535;21973;22006.44983875352;19236.519087022938;19834.702479462532;19920.3027894023;20147.496312591717;20301;18356.541086472946;18952.24191032343;19278.903605481883;19282.22871085141;19790.406287811387;17911.462656120337;19043.619127617647;19350.90324548368;19378.709319360503;19521.804781952;13267.203967761965;13423.731525369243;13920.930395347958;14139.505117321256;14511.1293322401;13975.879794385051;14550;14606.196659183846;15093.773593395888;15137.243137843285 18:31:07 INFO - PID 11593 | __end_tp_report 18:31:07 INFO - PID 11593 | __start_cc_report 18:31:07 INFO - PID 11593 | _x_x_mozilla_cycle_collect,363 18:31:07 INFO - PID 11593 | __end_cc_report 18:31:07 INFO - PID 11593 | __startTimestamp1500341467856__endTimestamp 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | ------- Summary: start ------- 18:31:07 INFO - PID 11593 | Number of tests: 6 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | [#0] dojo.html Cycles:165 Average:6003.07 Median:6237.31 stddev:703.20 (11.3%) stddev-sans-first:666.21 18:31:07 INFO - PID 11593 | Values: 3053.9 3088.8 3091.8 3106.3 3115.1 5987.5 6036.6 6069.8 6086.9 6116.9 6074.4 6128.2 6136.0 6144.6 6154.8 5564.3 5570.8 5612.9 5620.3 5623.6 5572.2 5597.0 5613.1 5618.9 5633.9 5601.9 5602.8 5621.1 5621.2 5639.7 5573.6 5610.7 5615.2 5626.0 5630.9 5539.9 5550.9 5563.6 5570.4 5659.5 5958.4 6022.9 6077.7 6077.8 6128.7 6465.0 6503.2 6531.5 6543.2 6563.5 6365.5 6469.5 6470.3 6480.5 6506.7 6008.2 6022.5 6046.7 6072.5 6077.3 6696.2 6723.2 6745.1 6759.0 6843.9 6335.1 6398.5 6402.6 6484.2 6508.0 6479.8 6513.9 6555.9 6592.7 6596.4 6369.6 6440.9 6455.5 6461.2 6478.0 6467.4 6524.1 6544.2 6546.9 6625.9 5297.5 5318.2 5361.9 5378.5 5382.9 4439.1 4508.8 4520.3 4526.4 4528.1 5653.9 5660.0 5660.6 5666.4 5674.1 6543.6 6546.3 6546.5 6553.2 6559.6 5564.1 5595.4 5653.9 5661.9 5673.3 5728.1 5759.1 5775.1 5780.9 5806.0 5343.9 6329.3 6343.2 6349.5 6390.6 6484.9 6493.2 6501.8 6599.9 6619.1 6297.6 6304.7 6324.4 6389.8 6401.2 6311.5 6404.1 6427.1 6453.1 6511.6 6237.3 6550.9 6620.7 6636.5 6639.1 6338.5 6345.6 6403.0 6405.8 6408.0 6325.5 6335.5 6378.2 6421.5 6429.6 6183.0 6205.2 6209.1 6313.7 6314.7 6333.9 6336.9 6337.1 6380.8 6403.1 6097.4 6195.0 6205.8 6244.1 6281.1 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | [#1] ext.html Cycles:180 Average:36729.71 Median:32543.77 stddev:52711.33 (162.0%) stddev-sans-first:52790.05 18:31:07 INFO - PID 11593 | Values: 771.3 808.8 834.2 853.5 854.8 39261.6 39890.0 39940.8 40002.6 40076.6 36014.1 36334.6 36387.5 36512.5 36572.8 6732.8 6815.0 6819.2 6821.5 6856.8 6699.8 6770.6 6799.1 6819.0 6850.8 6766.4 6775.2 6786.2 6803.8 6804.2 6626.9 6690.1 6697.6 6738.9 6747.4 6002.1 6091.8 6110.0 6111.2 6116.6 32199.0 32344.7 32357.0 32515.2 32572.3 3379.3 3413.2 3416.0 3524.1 3637.0 30255.7 30470.4 30589.0 30610.0 30632.5 4783.7 4852.0 4885.4 4895.0 4979.7 174122.0 174263.0 176071.0 189487.1 190067.0 41211.2 46077.8 46653.3 46943.1 47010.8 49310.0 49405.8 49439.8 49511.0 49608.5 16173.8 16292.9 16326.4 16331.6 16342.9 13571.9 13574.8 13593.6 13595.9 13605.6 14339.0 14412.6 14425.6 14481.4 14556.7 5534.2 5760.4 5777.8 5876.2 5888.8 977.9 991.3 1014.8 1029.9 1036.2 22414.4 22661.8 22773.5 22929.7 23420.5 1072.3 1090.1 1115.4 1119.9 1128.6 11596.9 11980.1 12020.0 12061.8 12160.0 229011.0 230670.0 231442.0 232237.0 232781.0 51382.7 51618.0 51703.7 51717.0 51840.5 1410.2 1448.5 1465.5 1467.0 1479.9 21620.7 21862.6 22099.0 27293.0 27991.3 48993.3 49146.8 49222.0 49306.8 49539.3 192115.0 193719.0 193740.0 194033.0 195607.0 31191.8 31278.1 31680.2 31770.2 35574.6 41217.8 42123.8 42249.2 43793.8 44024.6 30398.7 31778.2 31804.0 31854.0 32222.8 30229.0 31068.4 31196.4 31328.2 31334.0 34199.7 34580.8 35057.5 35159.6 35628.1 28342.0 30479.8 30740.4 31352.0 31397.1 22841.4 23265.4 23420.4 23607.8 23677.9 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | [#2] jquery.html Cycles:180 Average:34585.09 Median:22381.77 stddev:86302.59 (385.6%) stddev-sans-first:86506.83 18:31:07 INFO - PID 11593 | Values: 538.7 549.1 552.8 564.2 593.9 21345.1 22549.4 22625.7 22633.3 22683.0 24052.0 25192.0 25193.1 25290.1 26644.5 6296.2 6407.0 6415.1 6419.4 6429.3 6260.7 6273.4 6325.7 6332.1 6342.7 3706.3 3805.5 6234.9 6295.3 6300.2 6278.7 6311.1 6346.3 6350.6 6351.6 5864.1 5897.4 5903.0 5919.4 5925.3 28597.4 29486.4 29763.0 29803.1 29837.7 1893.7 1949.4 1958.3 1962.7 1966.3 17931.8 18354.5 18443.5 18443.8 18476.9 2286.6 2296.3 2341.1 2357.9 2380.3 120529.0 125893.0 125927.4 126126.4 126825.0 14144.5 14547.3 14551.5 14817.0 15138.2 23179.8 23215.8 23226.9 23240.9 23282.9 10443.0 10588.3 10704.8 10758.5 10869.8 9874.9 9967.7 10081.8 10085.7 10206.6 8801.5 9070.2 9205.6 9248.7 9262.3 2411.8 2638.7 2703.5 2722.6 2769.4 950.9 959.5 960.2 985.6 991.8 18643.5 19319.8 19582.6 19741.7 19874.2 998.6 1036.2 1052.0 1052.2 1058.3 10618.6 10682.6 10777.4 10890.8 10958.9 502353.0 509991.0 524221.0 526290.0 526560.0 108317.0 108662.0 109005.0 109465.0 112410.0 1165.6 1212.7 1220.7 1224.7 1232.4 16157.9 16879.4 17055.5 17259.5 17860.9 49703.3 51571.7 52350.7 53843.0 54205.0 66188.0 66300.0 66596.7 66601.3 67056.7 14021.9 14243.6 14252.9 14304.4 14304.9 21475.5 22266.6 22275.3 22488.2 22768.7 17143.7 17631.6 17807.8 17880.9 17899.5 13816.0 14045.4 16084.8 16139.2 17053.1 19558.7 19695.7 19699.4 19783.7 19917.0 14677.3 14834.0 14861.2 14866.2 14880.7 15859.7 18634.3 18791.5 18804.4 18862.8 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | [#3] mootools.html Cycles:180 Average:8580.68 Median:2586.81 stddev:5808.79 (224.6%) stddev-sans-first:5799.65 18:31:07 INFO - PID 11593 | Values: 1346.3 1394.0 1398.9 1407.4 1413.7 13362.2 14040.5 14048.9 14055.7 14097.2 13745.4 14122.3 14125.5 14148.2 14150.4 2657.3 2706.4 2716.1 2724.9 2735.1 2563.5 2630.9 2636.8 2648.9 2652.8 2466.7 2521.2 2549.7 2573.9 2609.1 2621.6 2736.5 2738.5 2742.1 2745.2 2599.7 2610.4 2612.2 2612.6 2613.0 14000.7 14119.0 14186.9 14190.1 14270.6 7380.6 7721.0 7742.9 7747.7 7757.1 4653.6 4899.7 5021.9 5039.1 5073.1 1833.7 1874.7 1883.5 1951.6 1959.8 22961.8 23065.4 23139.8 23179.3 23281.9 6192.4 6262.4 6302.7 6320.3 6505.8 15733.1 15767.8 15797.2 15808.1 15845.6 7327.9 7445.2 7451.5 7454.8 7489.9 6771.7 6869.7 6928.9 6975.2 7024.5 4731.0 4871.8 4933.5 4934.0 5029.1 1373.7 1376.4 1382.5 1415.1 1430.6 873.7 900.0 925.6 933.4 941.1 7303.2 7307.8 7323.5 8691.2 8749.5 983.9 996.2 1022.7 1030.8 1034.6 5087.5 5177.3 5192.2 5284.6 5457.3 18251.7 19245.2 19300.0 19339.3 19362.8 17606.4 17729.4 17798.0 17945.0 17973.6 9756.8 10128.1 10497.7 10545.8 10724.4 7964.8 8172.8 8236.1 8329.5 8635.4 15377.2 16378.9 16393.8 16648.9 16739.5 16264.5 16387.8 16666.7 16712.9 16981.9 10970.2 11429.1 11463.6 11502.4 11551.9 13087.9 13167.3 13224.5 13280.7 13281.4 9840.6 10109.3 10191.8 10294.9 10340.1 10134.4 10328.6 10707.6 10848.8 10893.8 8965.4 8986.0 9118.9 9169.0 9234.0 6864.1 6929.4 6959.3 7007.2 7073.7 6831.8 6918.7 6944.4 6951.9 7038.1 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | [#4] prototype.html Cycles:180 Average:5418.83 Median:5938.43 stddev:1513.65 (25.5%) stddev-sans-first:1471.82 18:31:07 INFO - PID 11593 | Values: 480.9 481.9 485.4 517.5 518.3 6116.0 6117.6 6120.0 6185.7 6190.2 5829.2 5951.0 6040.5 6063.5 6081.3 4894.7 4940.2 4942.5 4974.4 4985.5 4820.7 4895.9 4912.8 4937.4 4953.9 4849.9 4868.6 4900.7 4901.4 4930.8 4669.9 4810.0 4819.0 4889.2 4901.9 4336.1 4364.8 4375.2 4387.7 4409.3 5804.6 5856.2 5878.8 5887.5 5925.9 5567.3 5596.0 5629.2 5641.2 5647.8 6331.1 6348.8 6376.6 6404.9 6417.1 4726.5 4753.2 4753.6 4759.6 4784.7 6665.6 6806.8 6822.0 6844.4 6851.6 4929.7 4980.8 4983.4 4992.7 4998.5 4959.8 5013.1 5022.5 5024.8 5026.4 5515.3 5549.1 5581.8 5586.3 5602.7 6384.6 6397.6 6425.4 6436.3 6531.3 1716.8 1746.5 1746.8 1753.6 1758.8 1121.6 1182.6 1198.0 1202.9 1211.9 5467.1 5523.9 5603.5 5621.7 5643.6 6577.0 6587.6 6603.1 6617.7 6627.4 5627.9 5676.1 5722.7 5755.1 5775.7 3464.3 3489.2 3492.5 3494.3 3552.7 6360.1 6369.0 6371.7 6433.2 6436.4 6746.7 6767.4 6775.6 6785.6 6791.7 6345.6 6368.8 6426.6 6433.2 6438.6 6594.1 6653.5 6662.1 6664.8 6676.9 6411.8 6464.6 6550.6 6565.0 6591.2 6207.9 6341.6 6345.5 6358.4 6382.5 5352.5 5360.5 5373.2 5412.8 5419.3 6514.5 6581.5 6616.5 6624.3 6673.2 6390.2 6411.7 6433.7 6444.4 6450.5 6377.1 6427.2 6436.2 6501.1 6535.1 6277.7 6314.6 6370.3 6386.2 6405.6 5982.5 6320.6 6397.9 6428.7 6467.0 6090.9 6147.7 6208.8 6221.7 6275.9 18:31:07 INFO - PID 11593 | 18:31:07 INFO - PID 11593 | [#5] yui.html Cycles:180 Average:22900.34 Median:20762.69 stddev:30239.33 (145.6%) stddev-sans-first:30279.09 18:31:07 INFO - PID 11593 | Values: 913.8 939.0 939.1 943.4 960.2 17317.6 18153.1 18155.7 18261.5 21139.3 20598.7 21208.5 21318.4 21475.6 21538.5 1031.5 1067.8 1069.4 1070.9 1077.3 1053.2 1056.6 1058.4 1070.7 1071.3 1018.5 1063.3 1122.6 1124.8 1126.0 1093.4 1099.4 1101.3 1105.2 1106.4 795.7 800.5 800.8 802.2 803.5 18773.0 19358.6 19502.8 19687.0 20419.1 20305.5 20652.0 20991.7 21046.6 21430.6 15719.8 15843.8 15905.6 15943.1 16368.3 3620.1 3623.8 3635.1 3644.8 3654.7 121159.0 122358.0 127583.4 127873.4 128633.4 15049.2 15944.0 16001.8 16160.4 16282.3 29223.6 29881.9 30066.0 30080.1 30292.0 8586.9 8664.5 8676.9 8721.8 8777.0 3993.1 4135.5 4153.3 4154.3 4155.8 5075.9 5139.5 5151.9 5164.4 5182.3 923.0 944.9 954.7 962.7 967.8 683.3 687.9 702.8 707.5 720.8 11456.6 11741.6 11955.1 12007.9 12415.4 683.4 689.0 702.0 703.7 704.7 5488.3 5547.8 5554.1 5560.3 5865.7 95279.5 99644.0 100012.0 102471.0 107859.0 88067.6 91005.5 91367.5 92838.0 93005.0 73456.0 73703.6 75722.0 79238.6 81448.6 11138.4 12273.4 12984.6 13012.6 13143.7 32530.5 33818.7 35741.3 36072.0 37559.2 64033.7 64223.7 64628.7 66280.7 69111.0 18988.9 18992.3 19170.4 19189.0 19366.5 20316.2 20873.4 21350.4 21973.0 22006.4 19236.5 19834.7 19920.3 20147.5 20301.0 18356.5 18952.2 19278.9 19282.2 19790.4 17911.5 19043.6 19350.9 19378.7 19521.8 13267.2 13423.7 13920.9 14139.5 14511.1 13975.9 14550.0 14606.2 15093.8 15137.2 18:31:07 INFO - PID 11593 | -------- Summary: end -------- 18:31:07 INFO - PID 11593 | 18:31:08 INFO - TEST-INFO | 11593: exit 0 18:31:08 INFO - TEST-OK | dromaeo_css | took 1111111ms 18:31:08 INFO - TEST-START | kraken 18:31:08 INFO - Initialising browser for kraken test... 18:31:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39989/getInfo.html -profile /tmp/tmpL3aPQ4/profile 18:31:08 INFO - TEST-INFO | started process 12010 (/builds/slave/test/build/application/firefox/firefox http://localhost:39989/getInfo.html) 18:31:14 INFO - TEST-INFO | 12010: exit 0 18:31:14 INFO - Browser initialized. 18:31:14 INFO - Running cycle 1/1 for kraken test... 18:31:14 INFO - TEST-INFO | started process 12237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL3aPQ4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:26 INFO - PID 12237 | RSS: Main: 170893312 18:31:26 INFO - PID 12237 | 18:31:28 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:29 INFO - PID 12237 | RSS: Main: 177340416 18:31:29 INFO - PID 12237 | 18:31:32 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-dft) 18:31:32 INFO - PID 12237 | RSS: Main: 177614848 18:31:32 INFO - PID 12237 | 18:31:35 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-fft) 18:31:36 INFO - PID 12237 | RSS: Main: 170881024 18:31:36 INFO - PID 12237 | 18:31:38 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39989/tests/kraken/driver.html?testName=audio-oscillator) 18:31:39 INFO - PID 12237 | RSS: Main: 172195840 18:31:39 INFO - PID 12237 | 18:31:41 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:41 INFO - PID 12237 | RSS: Main: 172216320 18:31:41 INFO - PID 12237 | 18:31:44 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39989/tests/kraken/driver.html?testName=imaging-darkroom) 18:31:45 INFO - PID 12237 | RSS: Main: 175366144 18:31:45 INFO - PID 12237 | 18:31:48 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39989/tests/kraken/driver.html?testName=imaging-desaturate) 18:31:49 INFO - PID 12237 | RSS: Main: 177209344 18:31:49 INFO - PID 12237 | 18:31:52 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39989/tests/kraken/driver.html?testName=json-parse-financial) 18:31:52 INFO - PID 12237 | RSS: Main: 175771648 18:31:52 INFO - PID 12237 | 18:31:54 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:31:55 INFO - PID 12237 | RSS: Main: 175820800 18:31:55 INFO - PID 12237 | 18:31:56 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:31:57 INFO - PID 12237 | RSS: Main: 175976448 18:31:57 INFO - PID 12237 | 18:31:59 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:00 INFO - PID 12237 | RSS: Main: 176279552 18:32:00 INFO - PID 12237 | 18:32:02 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:03 INFO - PID 12237 | RSS: Main: 175587328 18:32:03 INFO - PID 12237 | 18:32:06 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:07 INFO - PID 12237 | RSS: Main: 176263168 18:32:07 INFO - PID 12237 | 18:32:08 INFO - PID 12237 | Cycle 1(1): loaded http://localhost:39989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39989/tests/kraken/driver.html?testName=ai-astar) 18:32:09 INFO - PID 12237 | RSS: Main: 177364992 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | __start_tp_report 18:32:09 INFO - PID 12237 | _x_x_mozilla_page_load 18:32:09 INFO - PID 12237 | _x_x_mozilla_page_load_details 18:32:09 INFO - PID 12237 | |i|pagename|runs| 18:32:09 INFO - PID 12237 | |0;ai-astar;103;109;97;109;110;109;109;109;107;108 18:32:09 INFO - PID 12237 | |1;audio-beat-detection;155;119;144;174;123;152;150;122;169;127 18:32:09 INFO - PID 12237 | |2;audio-dft;162;163;162;161;160;167;162;169;170;162 18:32:09 INFO - PID 12237 | |3;audio-fft;77;74;77;170;75;75;74;75;95;73 18:32:09 INFO - PID 12237 | |4;audio-oscillator;82;82;138;82;80;81;129;83;80;129 18:32:09 INFO - PID 12237 | |5;imaging-gaussian-blur;114;113;114;114;114;114;113;114;114;114 18:32:09 INFO - PID 12237 | |6;imaging-darkroom;149;150;150;151;149;152;150;149;150;150 18:32:09 INFO - PID 12237 | |7;imaging-desaturate;94;94;94;94;94;94;93;95;94;93 18:32:09 INFO - PID 12237 | |8;json-parse-financial;54;53;64;62;56;57;53;55;55;58 18:32:09 INFO - PID 12237 | |9;json-stringify-tinderbox;55;55;54;54;54;55;55;54;54;54 18:32:09 INFO - PID 12237 | |10;stanford-crypto-aes;78;78;79;78;79;77;80;78;78;78 18:32:09 INFO - PID 12237 | |11;stanford-crypto-ccm;135;135;136;133;125;124;134;133;134;175 18:32:09 INFO - PID 12237 | |12;stanford-crypto-pbkdf2;182;201;178;182;192;182;183;207;181;187 18:32:09 INFO - PID 12237 | |13;stanford-crypto-sha256-iterative;73;71;73;68;69;70;67;74;67;66 18:32:09 INFO - PID 12237 | __end_tp_report 18:32:09 INFO - PID 12237 | __start_cc_report 18:32:09 INFO - PID 12237 | _x_x_mozilla_cycle_collect,939 18:32:09 INFO - PID 12237 | __end_cc_report 18:32:09 INFO - PID 12237 | __startTimestamp1500341529198__endTimestamp 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | ------- Summary: start ------- 18:32:09 INFO - PID 12237 | Number of tests: 14 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:4.03 (3.7%) stddev-sans-first:4.00 18:32:09 INFO - PID 12237 | Values: 103.0 109.0 97.0 109.0 110.0 109.0 109.0 109.0 107.0 108.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:151.00 stddev:19.95 (13.2%) stddev-sans-first:20.72 18:32:09 INFO - PID 12237 | Values: 155.0 119.0 144.0 174.0 123.0 152.0 150.0 122.0 169.0 127.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#2] audio-dft Cycles:10 Average:163.80 Median:162.50 stddev:3.52 (2.2%) stddev-sans-first:3.67 18:32:09 INFO - PID 12237 | Values: 162.0 163.0 162.0 161.0 160.0 167.0 162.0 169.0 170.0 162.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#3] audio-fft Cycles:10 Average:86.50 Median:75.00 stddev:30.03 (40.0%) stddev-sans-first:31.65 18:32:09 INFO - PID 12237 | Values: 77.0 74.0 77.0 170.0 75.0 75.0 74.0 75.0 95.0 73.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:81.50 stddev:24.57 (30.1%) stddev-sans-first:25.48 18:32:09 INFO - PID 12237 | Values: 82.0 82.0 138.0 82.0 80.0 81.0 129.0 83.0 80.0 129.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:32:09 INFO - PID 12237 | Values: 114.0 113.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:150.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 18:32:09 INFO - PID 12237 | Values: 149.0 150.0 150.0 151.0 149.0 152.0 150.0 149.0 150.0 150.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:32:09 INFO - PID 12237 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 95.0 94.0 93.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.50 stddev:3.71 (6.6%) stddev-sans-first:3.81 18:32:09 INFO - PID 12237 | Values: 54.0 53.0 64.0 62.0 56.0 57.0 53.0 55.0 55.0 58.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 18:32:09 INFO - PID 12237 | Values: 55.0 55.0 54.0 54.0 54.0 55.0 55.0 54.0 54.0 54.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:0.82 (1.1%) stddev-sans-first:0.87 18:32:09 INFO - PID 12237 | Values: 78.0 78.0 79.0 78.0 79.0 77.0 80.0 78.0 78.0 78.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:134.50 stddev:14.19 (10.6%) stddev-sans-first:15.04 18:32:09 INFO - PID 12237 | Values: 135.0 135.0 136.0 133.0 125.0 124.0 134.0 133.0 134.0 175.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:185.00 stddev:9.58 (5.2%) stddev-sans-first:9.96 18:32:09 INFO - PID 12237 | Values: 182.0 201.0 178.0 182.0 192.0 182.0 183.0 207.0 181.0 187.0 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.50 stddev:2.86 (4.1%) stddev-sans-first:2.79 18:32:09 INFO - PID 12237 | Values: 73.0 71.0 73.0 68.0 69.0 70.0 67.0 74.0 67.0 66.0 18:32:09 INFO - PID 12237 | -------- Summary: end -------- 18:32:09 INFO - PID 12237 | 18:32:09 INFO - PID 12237 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:32:09 INFO - TEST-INFO | 12237: exit 0 18:32:09 INFO - TEST-OK | kraken | took 61459ms 18:32:09 INFO - SUITE-END | took 1172s 18:32:10 INFO - Completed test suite (00:19:32) 18:32:10 INFO - javascript benchmark 18:32:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8314.26184614282, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3053.9083827485183, 3088.752899768019, 3091.7951585843257, 3106.301082256491, 3115.112193024987, 5987.461128498434, 6036.5774395792205, 6069.78755743549, 6086.939130608693, 6116.908246376308, 6074.392560743923, 6128.172696685946, 6136.0, 6144.631322120665, 6154.753809847601, 5564.276644036269, 5570.832875013756, 5612.859678508029, 5620.325560932693, 5623.550115990711, 5572.192032155335, 5596.972015139919, 5613.073842815951, 5618.8595285118, 5633.887322253573, 5601.887962240753, 5602.831915042555, 5621.072523033715, 5621.184928185411, 5639.746211420475, 5573.554115670757, 5610.747516361774, 5615.1857980592995, 5625.971870140664, 5630.943690563124, 5539.892021595697, 5550.944490555084, 5563.554915606742, 5570.387257401723, 5659.547236221134, 5958.433948774859, 6022.909656355158, 6077.726502307407, 6077.848053798646, 6128.724207410677, 6464.967675161641, 6503.15458990333, 6531.477481801445, 6543.1821022372205, 6563.507736919702, 6365.490760739176, 6469.547131700783, 6470.2559205691, 6480.546361754679, 6506.739730410778, 6008.188894499231, 6022.488088512482, 6046.727897244635, 6072.544559158081, 6077.270727512682, 6696.196456425211, 6723.193216813916, 6745.123133992648, 6759.0, 6843.863122737517, 6335.144755458046, 6398.520110991694, 6402.551821372459, 6484.1894763154605, 6508.0, 6479.773207937794, 6513.934860651428, 6555.868882622321, 6592.736290548421, 6596.373344532261, 6369.5541312108635, 6440.935590644033, 6455.548111632234, 6461.19235095613, 6478.02829575558, 6467.417932386108, 6524.086627872101, 6544.214694236722, 6546.869062618816, 6625.86748265032, 5297.549708274762, 5318.22885681574, 5361.919571206435, 5378.462153784669, 5382.946170538323, 4439.134368798053, 4508.819647214074, 4520.299353600197, 4526.4115664964, 4528.117017181618, 5653.943460565424, 5660.037793575102, 5660.575456840754, 5666.376698563155, 5674.092145256739, 6543.640099794462, 6546.345365463416, 6546.476281897437, 6553.2136143663065, 6559.639219842859, 5564.054110801174, 5595.356533998561, 5653.943460565424, 5661.943380566224, 5673.262475878109, 5728.140778883201, 5759.107338362561, 5775.104858746901, 5780.913286300709, 5806.042003069446, 5343.946560534345, 6329.3354197809485, 6343.207099112611, 6349.460295874902, 6390.552661313756, 6484.902726459107, 6493.22081350241, 6501.837454063686, 6599.868002639824, 6619.139511863427, 6297.55917085818, 6304.747810087545, 6324.430801227728, 6389.808305750835, 6401.231852177769, 6311.526635502264, 6404.135441715309, 6427.0680751289865, 6453.128827608213, 6511.609303441809, 6237.313895471585, 6550.868982620225, 6620.735170593315, 6636.535442518977, 6639.070530125883, 6338.524610654127, 6345.619262844244, 6403.039544068427, 6405.807825765234, 6407.967960160356, 6325.525585581007, 6335.461485773577, 6378.170837791243, 6421.486281097595, 6429.58207716497, 6182.969085154546, 6205.22434695663, 6209.099680546386, 6313.715882785194, 6314.715837787402, 6333.873322533432, 6336.873262534816, 6337.112804207322, 6380.840478988062, 6403.135576697273, 6097.420745029215, 6195.0, 6205.81382558524, 6244.063390491463, 6281.089242059968], "value": 5951.001887311519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.2942657468417, 808.7978005498626, 834.2491757418322, 853.4665833853842, 854.756394427588, 39261.60738392619, 39890.0, 39940.80029599852, 40002.59997400025, 40076.59923400765, 36014.09964750876, 36334.63665363345, 36387.454188187134, 36512.45231321525, 36572.81713591435, 6732.764353247638, 6815.045893574904, 6819.24988251292, 6821.45428365732, 6856.760013399532, 6699.799006029827, 6770.627615481147, 6799.116114905056, 6818.9659051704675, 6850.7602233921825, 6766.424853887425, 6775.186977562699, 6786.151731033621, 6803.761868334609, 6804.21751498579, 6626.8674626507445, 6690.096836927026, 6697.5646582972195, 6738.898916516257, 6747.392734653905, 6002.069679199731, 6091.817245482643, 6110.022396416596, 6111.2055432793895, 6116.571839971204, 32199.0, 32344.676553234403, 32357.0, 32515.187120321945, 32572.348553028805, 3379.2734562069027, 3413.2320227948762, 3415.9658403415897, 3524.101354154674, 3637.0, 30255.697443025507, 30470.390592188254, 30589.0, 30610.0, 30632.54051189234, 4783.7129772213775, 4851.975740121276, 4885.36490256268, 4894.975525122387, 4979.701217926936, 174122.0, 174262.99999999872, 176071.0, 189487.05256473768, 190067.0, 41211.1757764849, 46077.7696111524, 46653.30020049702, 46943.06113877772, 47010.76494617505, 49310.0, 49405.75297123563, 49439.752801235765, 49511.0, 49608.50391496039, 16173.757393639105, 16292.918535407483, 16326.428574999818, 16331.59171020734, 16342.918285408497, 13571.864281357257, 13574.796378054338, 13593.59219223425, 13595.932020340033, 13605.591832245049, 14339.0, 14412.567622971328, 14425.567232983027, 14481.420743170365, 14556.708865822624, 5534.169874518774, 5760.395158508296, 5777.768889244468, 5876.236089308448, 5888.823335299948, 977.8582105594792, 991.2615101749243, 1014.7868947520956, 1029.907308342238, 1036.160709825044, 22414.439639009157, 22661.79039625489, 22773.544529109655, 22929.656055159187, 23420.531589368456, 1072.3137192197, 1090.138790355626, 1115.4367044642404, 1119.9160062995309, 1128.5824245029391, 11596.884031159749, 11980.101492388107, 12020.0, 12061.758764824654, 12159.96640285568, 229011.0, 230670.0, 231442.0, 232237.0, 232781.0, 51382.74308628433, 51618.0, 51703.74148129235, 51717.0, 51840.48159518432, 1410.153907655419, 1448.5364683301452, 1465.4870795221586, 1466.9779953300708, 1479.9186044767425, 21620.67568986435, 21862.562748744618, 22099.0, 27293.0, 27991.300217494725, 48993.2651010228, 49146.75426622844, 49222.0, 49306.75346623244, 49539.25691114708, 192115.0, 193719.0, 193740.0, 194033.0, 195607.0, 31191.844040780557, 31278.06165815029, 31680.207994800316, 31770.205744856565, 35574.64425355817, 41217.793911030254, 42123.7893810529, 42249.155016898876, 43793.78103109464, 44024.55975440205, 30398.696013039586, 31778.205544861565, 31804.0, 31854.0, 32222.83888580636, 30229.0, 31068.37863242677, 31196.376072478877, 31328.21679458032, 31334.0, 34199.658003419645, 34580.82709586436, 35057.474137888465, 35159.64840351564, 35628.10929726777, 28342.0, 30479.847600761856, 30740.385192296475, 31352.0, 31397.05808825739, 22841.428964276027, 23265.418364540346, 23420.414489637897, 23607.763922360555, 23677.881610592525], "value": 16574.25722870281, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.6606437944099, 549.1104410854416, 552.7733629212024, 564.184753031869, 593.8871614393265, 21345.146194152236, 22549.436264093405, 22625.660615090765, 22633.321000369975, 22683.0, 24052.0, 25192.0, 25193.118240861575, 25290.114845980395, 26644.467110657824, 6296.244450665927, 6407.03894415839, 6415.06981487684, 6419.422251997319, 6429.32492088331, 6260.749570017217, 6273.404026617487, 6325.746970121189, 6332.1135041094285, 6342.714577843985, 3706.3328600851837, 3805.46723458716, 6234.875302493947, 6295.338989406115, 6300.21247344082, 6278.6860656966965, 6311.084892690557, 6346.30190679025, 6350.587211831216, 6351.555391122623, 5864.149698293766, 5897.439743224387, 5902.996490596609, 5919.408059194089, 5925.34821169671, 28597.428051439063, 29486.410271794444, 29763.0, 29803.10590682283, 29837.70162298371, 1893.6591413545557, 1949.4054313434397, 1958.2852258925532, 1962.7252184694155, 1966.3412756726514, 17931.820681793146, 18354.541136471562, 18443.538911527186, 18443.815561844345, 18476.907615461972, 2286.5998450271304, 2296.2766728480474, 2341.110378056345, 2357.905683772647, 2380.2502211803226, 120529.0, 125893.0, 125927.37036314851, 126126.36936815258, 126824.99999999908, 14144.504942326968, 14547.27263636814, 14551.490697825526, 14817.0, 15138.167400792841, 23179.768202318097, 23215.767842321362, 23226.883865580563, 23240.883795581252, 23282.883585581963, 10443.0, 10588.311759735592, 10704.839427408595, 10758.462076896123, 10869.782604348027, 9874.901250987541, 9967.651132210338, 10081.798364032678, 10085.747856303504, 10206.591736330613, 8801.51591662452, 9070.18368346852, 9205.63177472907, 9248.722538323862, 9262.259019278441, 2411.807055435596, 2638.7493188147096, 2703.513367593852, 2722.632444619951, 2769.418422131335, 950.9001554836781, 959.5010594490965, 960.1934375124929, 985.6205360936091, 991.8413053911339, 18643.533911652317, 19319.80680193208, 19582.608347832964, 19741.703874441893, 19874.205031798854, 998.5855869814092, 1036.1710631494775, 1051.9684409467727, 1052.2266134391216, 1058.3015209961388, 10618.575256989789, 10682.572697092184, 10777.353358798497, 10890.836637450444, 10958.890411095786, 502353.0, 509991.0, 524221.0, 526290.0, 526560.0, 108317.0, 108662.0, 109005.0, 109465.0, 112410.0, 1165.5745652836822, 1212.6907638552113, 1220.7070303127186, 1224.7121926347302, 1232.4330807828499, 16157.919210403872, 16879.40922067722, 17055.48833534996, 17259.482215533553, 17860.91069544644, 49703.25445118254, 51571.742141289054, 52350.73824630852, 53843.0, 54204.99999999842, 66188.0, 66300.0, 66596.6670166646, 66601.33398665952, 67056.66471667611, 14021.929890350482, 14243.572692819233, 14252.857471425568, 14304.356303966555, 14304.928475357556, 21475.463113421665, 22266.554668906854, 22275.331740047826, 22488.21291254798, 22768.658470123293, 17143.657126857142, 17631.559211019827, 17807.842490238094, 17880.92714437138, 17899.463016109537, 13816.0, 14045.367958442099, 16084.839151608334, 16139.193040348171, 17053.06208158563, 19558.706619400436, 19695.704564431257, 19699.409017728918, 19783.703244451634, 19917.0, 14677.339519721852, 14834.035787673767, 14861.182634955181, 14866.182359970306, 14880.702385952003, 15859.682806343577, 18634.254629814655, 18791.530211744815, 18804.435866924014, 18862.811371886106], "value": 11682.348910394068, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.3201083452855, 1394.0450791208023, 1398.8671076247754, 1407.4440595964588, 1413.6819215676471, 13362.198268103908, 14040.5085821996, 14048.92975535121, 14055.718885622282, 14097.154170749749, 13745.381457834397, 14122.293885305746, 14125.505607303747, 14148.221847798366, 14150.433982640681, 2657.2958166085955, 2706.3910620110414, 2716.1308381317986, 2724.850133242671, 2735.1384313941144, 2563.538563058649, 2630.8816103275303, 2636.7626913577774, 2648.9337766555896, 2652.801039922004, 2466.654668346433, 2521.2184222890965, 2549.7450254974538, 2573.9227823165334, 2609.073778808524, 2621.580547112462, 2736.5484695025298, 2738.52075886719, 2742.0951086141527, 2745.203890871655, 2599.6880374355196, 2610.4387556675406, 2612.242449689607, 2612.6472926155107, 2612.973870261292, 14000.719985600232, 14119.0, 14186.92906535471, 14190.148591084568, 14270.571882843427, 7380.557166570023, 7721.0, 7742.8838567421535, 7747.72882949094, 7757.146713861527, 4653.581177694024, 4899.681520701143, 5021.949780502221, 5039.118154322979, 5073.137566613685, 1833.706606942896, 1874.690676038452, 1883.5479484923658, 1951.5999220159833, 1959.8138176873174, 22961.77038229613, 23065.423364415856, 23139.76860231393, 23179.3046208614, 23281.883590582107, 6192.380761923861, 6262.436380725757, 6302.684865756693, 6320.273168585672, 6505.763904858062, 15733.134677592841, 15767.842321576867, 15797.21013949298, 15808.051516909023, 15845.603859903364, 7327.890081648779, 7445.218252083561, 7451.515651482637, 7454.813629659302, 7489.925100749032, 6771.6614169292325, 6869.725210991604, 6928.861422771516, 6975.232724400312, 7024.5434046786795, 4731.0, 4871.780769865293, 4933.531314525115, 4933.963867587775, 5029.06962211992, 1373.7183877305129, 1376.3875075591466, 1382.5368501552093, 1415.0872687116957, 1430.6351880270465, 873.7160422862595, 899.9820003599891, 925.6251218256617, 933.3979583168818, 941.091846368258, 7303.233160518175, 7307.7807665770115, 7323.487355885143, 8691.217790398894, 8749.47503149813, 983.8671779309844, 996.1731762636994, 1022.6880801355509, 1030.7783826477344, 1034.591336422109, 5087.491250874957, 5177.330822749083, 5192.247124166975, 5284.550813180846, 5457.345118585716, 18251.72622410665, 19245.230190792492, 19300.0, 19339.323123690603, 19362.8063719361, 17606.38377656776, 17729.37947171843, 17797.99999999974, 17945.0, 17973.640527189382, 9756.804863902682, 10128.088472037552, 10497.685069447776, 10545.84181237282, 10724.356538607708, 7964.840703185788, 8172.7956801080445, 8236.135205803545, 8329.541875196923, 8635.395522313378, 15377.231138443256, 16378.918105409397, 16393.754093688844, 16648.916755416147, 16739.497815065566, 16264.51206463808, 16387.83612163911, 16666.749998749787, 16712.916435418232, 16981.915090424467, 10970.177236707119, 11429.142814288794, 11463.598774042868, 11502.424878756196, 11551.942240288745, 13087.934560327138, 13167.341632918507, 13224.47102115943, 13280.667983300495, 13281.402336895057, 9840.557174927002, 10109.292349535463, 10191.796164076824, 10294.897051029393, 10340.121089707158, 10134.391936483835, 10328.638497652546, 10707.57169713234, 10848.783024339627, 10893.836592450962, 8965.417247878868, 8986.0, 9118.908810912071, 9169.0, 9233.953830230806, 6864.107666003388, 6929.376356127771, 6959.269276726073, 7007.229204787572, 7073.71705131789, 6831.760888368884, 6918.654067296515, 6944.4097251734165, 6951.8609627806145, 7038.120234970629], "value": 6306.214310114289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.94469136049366, 481.8506263058453, 485.40053034502387, 517.4799326676695, 518.2511271213097, 6115.969420152904, 6117.6329420234815, 6119.9596068668325, 6185.690715464231, 6190.1952746143, 5829.163813398787, 5951.0, 6040.516758659308, 6063.514918806496, 6081.270247570298, 4894.706317620937, 4940.2342636891335, 4942.481039490855, 4974.37820272466, 4985.451600323961, 4820.734859582722, 4895.853124406273, 4912.803487860481, 4937.382827146606, 4953.851384458472, 4849.933014736752, 4868.586170175539, 4900.6569540132205, 4901.362822833027, 4930.778114984816, 4669.883252918704, 4810.0, 4818.952080252569, 4889.168841296988, 4901.901961960759, 4336.119173339875, 4364.803583838735, 4375.234333991539, 4387.670924680661, 4409.272470042439, 5804.564657650651, 5856.209411729447, 5878.764849406019, 5887.470127688529, 5925.851853703649, 5567.3040869891265, 5595.972020139914, 5629.211910332557, 5641.182028605829, 5647.830565083054, 6331.14529538511, 6348.77779277723, 6376.617402955838, 6404.871902561923, 6417.117643825512, 4726.480087190441, 4753.2413007187215, 4753.619710423158, 4759.61923046159, 4784.66507344486, 6665.600063996128, 6806.829829254259, 6821.965890170567, 6844.4182244509275, 6851.588904665686, 4929.654924155347, 4980.800767969313, 4983.426905905795, 4992.700437973734, 4998.4501704813165, 4959.776810043557, 5013.147764879979, 5022.4977502249485, 5024.773885175176, 5026.421961474404, 5515.283013208337, 5549.112142057332, 5581.776728930798, 5586.273784408001, 5602.6918519481005, 6384.58500197495, 6397.584157029778, 6425.357464253537, 6436.3241859605005, 6531.3142120077655, 1716.768236288085, 1746.519707080563, 1746.8427841494072, 1753.5616095976006, 1758.78015248094, 1121.556984990924, 1182.6038277177242, 1197.952081916731, 1202.8676845546984, 1211.8909298163207, 5467.0865197180665, 5523.889522209533, 5603.495685388253, 5621.718914054362, 5643.5767317451355, 6576.980568011966, 6587.604743715392, 6603.141591593062, 6617.735290588323, 6627.403533681992, 5627.915581266284, 5676.083502383342, 5722.713864306768, 5755.1367294906095, 5775.711214439261, 3464.255185135208, 3489.197484578561, 3492.511048453219, 3494.3186078714407, 3552.715782737375, 6360.074365791787, 6368.993766963392, 6371.681415929185, 6433.228012638513, 6436.35636436362, 6746.696398662072, 6767.424768894699, 6775.559588626724, 6785.59286442815, 6791.728330866808, 6345.555811093363, 6368.75193651232, 6426.550141490145, 6433.163688720437, 6438.613683178931, 6594.142761440982, 6653.467722582279, 6662.100616416722, 6664.83337916556, 6676.866462670817, 6411.839704007437, 6464.644444555594, 6550.6069635822005, 6565.045729421684, 6591.242007169045, 6207.937920620736, 6341.619502829661, 6345.460635846005, 6358.427741503287, 6382.489400847828, 5352.4915133062295, 5360.463953604608, 5373.194020896741, 5412.810551630674, 5419.268398766116, 6514.51141164424, 6581.539292249497, 6616.503762217949, 6624.310800745779, 6673.232578253369, 6390.233172019201, 6411.711472983634, 6433.678316084083, 6444.387783160592, 6450.451711604566, 6377.139086223486, 6427.228732552124, 6436.195475565554, 6501.122348482894, 6535.072153714571, 6277.686115694289, 6314.55798094129, 6370.267419246846, 6386.16979792624, 6405.590770030586, 5982.46157845796, 6320.5891617044745, 6397.8720425592155, 6428.6785660717715, 6466.967665161644, 6090.9390906090375, 6147.692615369303, 6208.782692605737, 6221.688915554295, 6275.905861411991], "value": 4970.509202728608, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [913.7898283394824, 939.0468674295589, 939.0890835889185, 943.391512474454, 960.2366118935446, 17317.567060823487, 18153.092345382727, 18155.727664085032, 18261.543461413472, 21139.2601258956, 20598.691019634716, 21208.4697882553, 21318.36044918651, 21475.57048859022, 21538.461538461546, 1031.5203430404842, 1067.8398240263946, 1069.4118234970774, 1070.9303895246824, 1077.2997551591488, 1053.2364036073861, 1056.550965839519, 1058.4443167337133, 1070.7109080548248, 1071.2822408985999, 1018.4856647393057, 1063.2557209953025, 1122.573422099601, 1124.8031594470976, 1126.0, 1093.436880006797, 1099.356876227404, 1101.2786624760795, 1105.2152971390321, 1106.3859557945336, 795.7055889320931, 800.5156880087579, 800.8318253166842, 802.2098233240254, 803.5259197073755, 18773.0, 19358.612827743505, 19502.804971950383, 19687.0, 20419.081141348674, 20305.49236269105, 20652.0, 20991.685124723143, 21046.579068418698, 21430.571388572298, 15719.764203536955, 15843.762343564857, 15905.602359940978, 15943.123128227942, 16368.263428145761, 3620.076880395509, 3623.8188090595363, 3635.0912271932016, 3644.835982380799, 3654.7258955578436, 121159.0, 122358.0, 127583.36208318899, 127873.36063319624, 128633.35683321524, 15049.247537623076, 15944.0, 16001.759973600405, 16160.353585856434, 16282.348706051625, 29223.56164657532, 29881.85059074734, 30066.0, 30080.097597072123, 30292.0, 8586.914130858611, 8664.523451210243, 8676.91323086761, 8721.781955451164, 8777.034526202113, 3993.1414745829206, 4135.503739551213, 4153.293940030202, 4154.335306351029, 4155.833766649361, 5075.923861142086, 5139.460356662571, 5151.9484805152215, 5164.354455693038, 5182.32629758129, 922.958466868979, 944.8771659684197, 954.7326748510428, 962.7256231973854, 967.7822489939707, 683.2620769568854, 687.9312068793081, 702.7961891051538, 707.4552594502204, 720.7909706185147, 11456.599019034295, 11741.647750567497, 11955.105672751139, 12007.939960300142, 12415.379231038412, 683.3576438148125, 688.9862202755917, 702.0452185028357, 703.7396163419465, 704.6864145455223, 5488.286522752017, 5547.834954659486, 5554.0835762099605, 5560.304961879765, 5865.67738774363, 95279.52360238154, 99644.0, 100012.0, 102471.0, 107859.0, 88067.55966220128, 91005.54497227471, 91367.54316228509, 92838.0, 93005.0, 73456.0, 73703.63148184225, 75722.0, 79238.6038069806, 81448.59275703703, 11138.387388693376, 12273.447694853574, 12984.610461686165, 13012.609621711365, 13143.671408214488, 32530.51204231891, 33818.66181338155, 35741.285174296885, 36072.0, 37559.248815023, 64033.67983160241, 64223.678881605294, 64628.67685661541, 66280.6685966567, 69111.0, 18988.905055474635, 18992.335268265546, 19170.424887253404, 19189.0, 19366.515837104183, 20316.187352505734, 20873.373798786062, 21350.35948921535, 21973.0, 22006.44983875352, 19236.519087022938, 19834.702479462532, 19920.3027894023, 20147.496312591717, 20301.0, 18356.541086472946, 18952.24191032343, 19278.903605481883, 19282.22871085141, 19790.406287811387, 17911.462656120337, 19043.619127617647, 19350.90324548368, 19378.709319360503, 19521.804781952, 13267.203967761965, 13423.731525369243, 13920.930395347958, 14139.505117321256, 14511.1293322401, 13975.879794385051, 14550.0, 14606.196659183846, 15093.773593395888, 15137.243137843285], "value": 9145.165557524133, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 109.0, 97.0, 109.0, 110.0, 109.0, 109.0, 109.0, 107.0, 108.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 144.0, 174.0, 123.0, 152.0, 150.0, 122.0, 169.0, 127.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 161.0, 160.0, 167.0, 162.0, 169.0, 170.0, 162.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 77.0, 170.0, 75.0, 75.0, 74.0, 75.0, 95.0, 73.0], "value": 86.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 138.0, 82.0, 80.0, 81.0, 129.0, 83.0, 80.0, 129.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 150.0, 151.0, 149.0, 152.0, 150.0, 149.0, 150.0, 150.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 95.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 64.0, 62.0, 56.0, 57.0, 53.0, 55.0, 55.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 78.0, 79.0, 77.0, 80.0, 78.0, 78.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 135.0, 136.0, 133.0, 125.0, 124.0, 134.0, 133.0, 134.0, 175.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 201.0, 178.0, 182.0, 192.0, 182.0, 183.0, 207.0, 181.0, 187.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 73.0, 68.0, 69.0, 70.0, 67.0, 74.0, 67.0, 66.0], "value": 69.8, "unit": "score"}]}]} 18:32:10 INFO - Return code: 0 18:32:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:10 INFO - # TBPL SUCCESS # 18:32:10 INFO - Running post-action listener: _package_coverage_data 18:32:10 INFO - Running post-action listener: _resource_record_post_action 18:32:10 INFO - [mozharness: 2017-07-18 01:32:10.516278Z] Finished run-tests step (success) 18:32:10 INFO - Running post-run listener: _resource_record_post_run 18:32:10 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 12288; Write bytes: 437448704; Read time: 24; Write time: 283384 18:32:10 INFO - TinderboxPrint: CPU usage
13.1% 18:32:10 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 24 18:32:10 INFO - TinderboxPrint: I/O write bytes / time
437,448,704 / 283,384 18:32:10 INFO - TinderboxPrint: CPU idle
8,253.1 (86.9%) 18:32:10 INFO - TinderboxPrint: CPU user
1,198.6 (12.6%) 18:32:10 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107405312; Read time: 0; Write time: 74352 18:32:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:32:10 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 330043392; Read time: 12; Write time: 209032 18:32:11 INFO - Running post-run listener: _upload_blobber_files 18:32:11 INFO - Blob upload gear active. 18:32:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:11 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 18:32:11 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'] 18:32:11 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 18:32:11 INFO - Traceback (most recent call last): 18:32:11 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 18:32:11 INFO - main() 18:32:11 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 18:32:11 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 18:32:11 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 18:32:11 INFO - return set(response.json().get('whitelist', [])) 18:32:11 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 18:32:11 INFO - return json.loads(self.text or self.content, **kwargs) 18:32:11 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 18:32:11 INFO - return _default_decoder.decode(s) 18:32:11 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 18:32:11 INFO - obj, end = self.raw_decode(s) 18:32:11 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 18:32:11 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 18:32:11 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 18:32:11 ERROR - Return code: 1 18:32:11 INFO - Setting buildbot property blobber_files to {} 18:32:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:11 INFO - Contents: 18:32:11 INFO - blobber_files:{} 18:32:11 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:11 INFO - Copying logs to upload dir... 18:32:11 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.513138 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-07-17 18:32:10.870312) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:10.878199) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/e_KBwBrBSNuUWab6NjyQ4Q/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:10.928923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:10.929251) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1500340093.910691-1615589404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004394 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:10.978638) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:32:10.981970) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:32:10.982282) ========= ========= Total master_lag: 0.18 =========