builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-008 starttime: 1504049356.35 results: success (0) revision: 8f221407c6006ac1de529da77ecc18431dde3e99 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.349286) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.349909) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.350181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.373225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.376604) ========= 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=32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 16:29:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.0M=0.001s 2017-08-29 16:29:16 (21.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100396 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.495210) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.495510) ========= 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=32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023967 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:16.544772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 15 secs) (at 2017-08-29 16:29:16.545072) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8f221407c6006ac1de529da77ecc18431dde3e99 --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 8f221407c6006ac1de529da77ecc18431dde3e99 --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=32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 16:29:16,348 truncating revision to first 12 chars 2017-08-29 16:29:16,348 Setting DEBUG logging. 2017-08-29 16:29:16,348 attempt 1/10 2017-08-29 16:29:16,348 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8f221407c600?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 16:29:16,896 attempt 1/10 2017-08-29 16:29:17,836 current task status: no status available at this point. state: STARTED 2017-08-29 16:29:17,837 sleeping for 10.00s (attempt 1/10) 2017-08-29 16:29:27,847 attempt 2/10 2017-08-29 16:29:28,145 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-29 16:29:31,224 unpacking tar archive at: mozilla-central-8f221407c600/testing/mozharness/ program finished with exit code 0 elapsedTime=15.410060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 15 secs) (at 2017-08-29 16:29:31.977396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:31.977722) ========= script_repo_revision: 8f221407c6006ac1de529da77ecc18431dde3e99 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:31.978088) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:31.978374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 16:29:31.990276) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-08-29 16:29:31.990622) ========= /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=32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:29:31 INFO - MultiFileLogger online at 20170829 16:29:31 in /builds/slave/test 16:29:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:29:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:29:31 INFO - {'append_to_log': False, 16:29:31 INFO - 'base_work_dir': '/builds/slave/test', 16:29:31 INFO - 'blob_upload_branch': 'Firefox', 16:29:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:29:31 INFO - 'branch': 'Firefox', 16:29:31 INFO - 'buildbot_json_path': 'buildprops.json', 16:29:31 INFO - 'code_coverage': False, 16:29:31 INFO - 'config_files': ('talos/linux_config.py',), 16:29:31 INFO - 'default_actions': ('clobber', 16:29:31 INFO - 'read-buildbot-config', 16:29:31 INFO - 'download-and-extract', 16:29:31 INFO - 'populate-webroot', 16:29:31 INFO - 'create-virtualenv', 16:29:31 INFO - 'install', 16:29:31 INFO - 'setup-mitmproxy', 16:29:31 INFO - 'run-tests'), 16:29:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:29:31 INFO - 'disable_ccov_upload': False, 16:29:31 INFO - 'disable_stylo': False, 16:29:31 INFO - 'download_minidump_stackwalk': True, 16:29:31 INFO - 'download_symbols': 'ondemand', 16:29:31 INFO - 'enable_stylo': False, 16:29:31 INFO - 'enable_webrender': False, 16:29:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:29:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:29:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:29:31 INFO - 'gecko_profile': False, 16:29:31 INFO - 'gecko_profile_interval': 0, 16:29:31 INFO - 'installer_path': 'installer.exe', 16:29:31 INFO - 'log_level': 'info', 16:29:31 INFO - 'log_name': 'talos', 16:29:31 INFO - 'log_to_console': True, 16:29:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:29:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:29:31 INFO - 'opt_config_files': (), 16:29:31 INFO - 'pip_index': False, 16:29:31 INFO - 'suite': 'dromaeojs-e10s', 16:29:31 INFO - 'system_bits': '32', 16:29:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:29:31 INFO - 'title': 'talos-linux64-ix-008', 16:29:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:29:31 INFO - 'use_talos_json': True, 16:29:31 INFO - 'verify': 'False', 16:29:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:29:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:29:31 INFO - 'work_dir': 'build'} 16:29:31 INFO - [mozharness: 2017-08-29 23:29:31.928285Z] Running clobber step. 16:29:31 INFO - Running pre-action listener: _resource_record_pre_action 16:29:31 INFO - Running main action method: clobber 16:29:31 INFO - rmtree: /builds/slave/test/build 16:29:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:29:32 INFO - Running post-action listener: _resource_record_post_action 16:29:32 INFO - [mozharness: 2017-08-29 23:29:32.925172Z] Finished clobber step (success) 16:29:32 INFO - [mozharness: 2017-08-29 23:29:32.925269Z] Running read-buildbot-config step. 16:29:32 INFO - Running pre-action listener: _resource_record_pre_action 16:29:32 INFO - Running main action method: read_buildbot_config 16:29:32 INFO - Using buildbot properties: 16:29:32 INFO - { 16:29:32 INFO - "project": "", 16:29:32 INFO - "product": "firefox", 16:29:32 INFO - "who": "kwierso@gmail.com", 16:29:32 INFO - "installer_path": "public/build/target.tar.bz2", 16:29:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 16:29:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:29:32 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 16:29:32 INFO - "stage_platform": "linux64-stylosequential", 16:29:32 INFO - "basedir": "/builds/slave/test", 16:29:32 INFO - "buildnumber": 50, 16:29:32 INFO - "platform": "ubuntu64_hw_styloseq", 16:29:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:29:32 INFO - "slavebuilddir": "test", 16:29:32 INFO - "taskId": "ct8z_pT7R8etjxTKX8U8qg", 16:29:32 INFO - "branch": "mozilla-central", 16:29:32 INFO - "script_repo_revision": "production", 16:29:32 INFO - "revision": "8f221407c6006ac1de529da77ecc18431dde3e99", 16:29:32 INFO - "slavename": "talos-linux64-ix-008", 16:29:32 INFO - "repo_path": "mozilla-central" 16:29:32 INFO - } 16:29:32 INFO - Finding installer, test and symbols from parent task. 16:29:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ct8z_pT7R8etjxTKX8U8qg'}, attempt #1 16:29:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ct8z_pT7R8etjxTKX8U8qg'}, attempt #1 16:29:33 INFO - Task dependencies: OLOX1rRMRjiLLTkG5J30Ew 16:29:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew'}, attempt #1 16:29:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:29:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json 16:29:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip 16:29:34 INFO - Running post-action listener: _resource_record_post_action 16:29:34 INFO - [mozharness: 2017-08-29 23:29:34.275096Z] Finished read-buildbot-config step (success) 16:29:34 INFO - [mozharness: 2017-08-29 23:29:34.275315Z] Running download-and-extract step. 16:29:34 INFO - Running pre-action listener: _resource_record_pre_action 16:29:34 INFO - Running main action method: download_and_extract 16:29:34 INFO - mkdir: /builds/slave/test/build/tests 16:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:34 INFO - trying https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json 16:29:34 INFO - Downloading https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:29:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:29:34 INFO - Downloaded 989 bytes. 16:29:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:29:34 INFO - Using the following test package requirements: 16:29:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:29:34 INFO - u'common': [u'target.common.tests.zip'], 16:29:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:29:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:29:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:29:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:29:34 INFO - u'mozbase': [u'target.common.tests.zip'], 16:29:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:29:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:29:34 INFO - u'web-platform': [u'target.common.tests.zip', 16:29:34 INFO - u'target.web-platform.tests.tar.gz'], 16:29:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:29:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:29:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip 16:29:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:29:34 INFO - Fetch https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip into memory 16:29:35 INFO - Content-Length response header: 54980876 16:29:35 INFO - Bytes received: 54980876 16:29:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:29:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip 16:29:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:29:46 INFO - Fetch https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.common.tests.zip into memory 16:29:47 INFO - Content-Length response header: 54980876 16:29:47 INFO - Bytes received: 54980876 16:29:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.talos.tests.zip 16:29:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:29:59 INFO - Fetch https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.talos.tests.zip into memory 16:30:00 INFO - Content-Length response header: 14496610 16:30:00 INFO - Bytes received: 14496610 16:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:00 INFO - trying https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:30:00 INFO - Downloading https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:30:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:30:02 INFO - Downloaded 62574194 bytes. 16:30:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:30:02 INFO - mkdir: /builds/slave/test/properties 16:30:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:30:02 INFO - Writing to file /builds/slave/test/properties/build_url 16:30:02 INFO - Contents: 16:30:02 INFO - build_url:https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 16:30:02 INFO - Running post-action listener: _resource_record_post_action 16:30:02 INFO - Running post-action listener: find_tests_for_verification 16:30:02 INFO - Running post-action listener: set_extra_try_arguments 16:30:02 INFO - [mozharness: 2017-08-29 23:30:02.066294Z] Finished download-and-extract step (success) 16:30:02 INFO - [mozharness: 2017-08-29 23:30:02.066411Z] Running populate-webroot step. 16:30:02 INFO - Running pre-action listener: _resource_record_pre_action 16:30:02 INFO - Running main action method: populate_webroot 16:30:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:30:02 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:30:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:30:02 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:30:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:02 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'tests': ['damp', 'tps']}, 16:30:02 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['damp', 'tps']}, 16:30:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:30:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:30:02 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:30:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:30:02 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:30:02 INFO - 'other-e10s': {'tests': ['a11yr', 16:30:02 INFO - 'ts_paint', 16:30:02 INFO - 'tpaint', 16:30:02 INFO - 'sessionrestore', 16:30:02 INFO - 'sessionrestore_many_windows', 16:30:02 INFO - 'sessionrestore_no_auto_restore', 16:30:02 INFO - 'tabpaint']}, 16:30:02 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['a11yr', 16:30:02 INFO - 'ts_paint', 16:30:02 INFO - 'tpaint', 16:30:02 INFO - 'sessionrestore', 16:30:02 INFO - 'sessionrestore_many_windows', 16:30:02 INFO - 'sessionrestore_no_auto_restore', 16:30:02 INFO - 'tabpaint']}, 16:30:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:30:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:30:02 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['bloom_basic_singleton']}, 16:30:02 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['bloom_basic']}, 16:30:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:30:02 INFO - 'tsvgr_opacity', 16:30:02 INFO - 'tart', 16:30:02 INFO - 'tscrollx', 16:30:02 INFO - 'tsvg_static']}, 16:30:02 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['tsvgx', 16:30:02 INFO - 'tsvgr_opacity', 16:30:02 INFO - 'tart', 16:30:02 INFO - 'tscrollx', 16:30:02 INFO - 'tsvg_static']}, 16:30:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:30:02 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo'], 16:30:02 INFO - 'tests': ['tp5o']}, 16:30:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:30:02 INFO - 'talos_options': ['--mitmproxy', 16:30:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:30:02 INFO - '--firstNonBlankPaint'], 16:30:02 INFO - 'tests': ['tp6_google', 16:30:02 INFO - 'tp6_youtube', 16:30:02 INFO - 'tp6_amazon', 16:30:02 INFO - 'tp6_facebook']}, 16:30:02 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo', 16:30:02 INFO - '--mitmproxy', 16:30:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:30:02 INFO - '--firstNonBlankPaint'], 16:30:02 INFO - 'tests': ['tp6_google', 16:30:02 INFO - 'tp6_youtube', 16:30:02 INFO - 'tp6_amazon', 16:30:02 INFO - 'tp6_facebook']}, 16:30:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo', 16:30:02 INFO - '--stylo-threads=1', 16:30:02 INFO - '--mitmproxy', 16:30:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:30:02 INFO - '--firstNonBlankPaint'], 16:30:02 INFO - 'tests': ['tp6_google', 16:30:02 INFO - 'tp6_youtube', 16:30:02 INFO - 'tp6_amazon', 16:30:02 INFO - 'tp6_facebook']}, 16:30:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'talos_options': ['--xperf_path', 16:30:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:02 INFO - 'tests': ['tp5n']}, 16:30:02 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:30:02 INFO - 'talos_options': ['--enable-stylo', 16:30:02 INFO - '--xperf_path', 16:30:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:02 INFO - 'tests': ['tp5n']}}} 16:30:02 INFO - Running post-action listener: _resource_record_post_action 16:30:02 INFO - [mozharness: 2017-08-29 23:30:02.084362Z] Finished populate-webroot step (success) 16:30:02 INFO - [mozharness: 2017-08-29 23:30:02.084469Z] Running create-virtualenv step. 16:30:02 INFO - Running pre-action listener: _resource_record_pre_action 16:30:02 INFO - Running main action method: create_virtualenv 16:30:02 INFO - Creating virtualenv /builds/slave/test/build/venv 16:30:02 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:30:02 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:30:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:30:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:30:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:30:02 INFO - Installing distribute.............................................................................................................................................................................................done. 16:30:02 INFO - Installing pip...............done. 16:30:02 INFO - Return code: 0 16:30:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:30:03 INFO - Reading from file tmpfile_stdout 16:30:03 INFO - Output received: 16:30:03 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:30:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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', 'pip>=1.5'] in /builds/slave/test/build 16:30: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 pip>=1.5 16:30:03 INFO - Using env: {'DISPLAY': ':0', 16:30:03 INFO - 'HOME': '/home/cltbld', 16:30:03 INFO - 'LANG': 'en_US.UTF-8', 16:30:03 INFO - 'LANGUAGE': 'en_US:en', 16:30:03 INFO - 'LOGNAME': 'cltbld', 16:30:03 INFO - 'MAIL': '/var/mail/cltbld', 16:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:03 INFO - 'MOZ_NO_REMOTE': '1', 16:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:03 INFO - 'NO_EM_RESTART': '1', 16:30:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:03 INFO - 'PWD': '/builds/slave/test', 16:30:03 INFO - 'SHELL': '/bin/bash', 16:30:03 INFO - 'SHLVL': '1', 16:30:03 INFO - 'TERM': 'linux', 16:30:03 INFO - 'TMOUT': '86400', 16:30:03 INFO - 'USER': 'cltbld', 16:30:03 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:03 INFO - '_': '/tools/buildbot/bin/python'} 16:30:04 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:30:04 INFO - Downloading/unpacking pip>=1.5 16:30:04 INFO - Running setup.py egg_info for package pip 16:30:04 INFO - warning: no files found matching 'pip/cacert.pem' 16:30:04 INFO - warning: no files found matching '*.html' under directory 'docs' 16:30:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:30:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:30:04 INFO - Installing collected packages: pip 16:30:04 INFO - Found existing installation: pip 1.1 16:30:04 INFO - Uninstalling pip: 16:30:04 INFO - Successfully uninstalled pip 16:30:04 INFO - Running setup.py install for pip 16:30:04 INFO - warning: no files found matching 'pip/cacert.pem' 16:30:04 INFO - warning: no files found matching '*.html' under directory 'docs' 16:30:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:30:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:30:04 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:30:04 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:30:04 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:30:04 INFO - Successfully installed pip 16:30:04 INFO - Cleaning up... 16:30:04 INFO - Return code: 0 16:30:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30: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', '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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:30: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 psutil>=3.1.1 16:30:04 INFO - Using env: {'DISPLAY': ':0', 16:30:04 INFO - 'HOME': '/home/cltbld', 16:30:04 INFO - 'LANG': 'en_US.UTF-8', 16:30:04 INFO - 'LANGUAGE': 'en_US:en', 16:30:04 INFO - 'LOGNAME': 'cltbld', 16:30:04 INFO - 'MAIL': '/var/mail/cltbld', 16:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:04 INFO - 'MOZ_NO_REMOTE': '1', 16:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:04 INFO - 'NO_EM_RESTART': '1', 16:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:04 INFO - 'PWD': '/builds/slave/test', 16:30:04 INFO - 'SHELL': '/bin/bash', 16:30:04 INFO - 'SHLVL': '1', 16:30:04 INFO - 'TERM': 'linux', 16:30:04 INFO - 'TMOUT': '86400', 16:30:04 INFO - 'USER': 'cltbld', 16:30:04 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:04 INFO - '_': '/tools/buildbot/bin/python'} 16:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:04 INFO - Downloading/unpacking psutil>=3.1.1 16:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:30:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:05 INFO - Installing collected packages: psutil 16:30:05 INFO - Running setup.py install for psutil 16:30:05 INFO - building 'psutil._psutil_linux' extension 16:30:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:30:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:30:05 INFO - building 'psutil._psutil_posix' extension 16:30:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:30:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:30:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:06 INFO - Successfully installed psutil 16:30:06 INFO - Cleaning up... 16:30:06 INFO - Return code: 0 16:30:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:30: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 mozsystemmonitor==0.3 16:30:06 INFO - Using env: {'DISPLAY': ':0', 16:30:06 INFO - 'HOME': '/home/cltbld', 16:30:06 INFO - 'LANG': 'en_US.UTF-8', 16:30:06 INFO - 'LANGUAGE': 'en_US:en', 16:30:06 INFO - 'LOGNAME': 'cltbld', 16:30:06 INFO - 'MAIL': '/var/mail/cltbld', 16:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:06 INFO - 'MOZ_NO_REMOTE': '1', 16:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:06 INFO - 'NO_EM_RESTART': '1', 16:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:06 INFO - 'PWD': '/builds/slave/test', 16:30:06 INFO - 'SHELL': '/bin/bash', 16:30:06 INFO - 'SHLVL': '1', 16:30:06 INFO - 'TERM': 'linux', 16:30:06 INFO - 'TMOUT': '86400', 16:30:06 INFO - 'USER': 'cltbld', 16:30:06 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:06 INFO - '_': '/tools/buildbot/bin/python'} 16:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:30: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 16:30: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 16:30: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 16:30: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 16:30:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:30:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:30:07 INFO - Installing collected packages: mozsystemmonitor 16:30:07 INFO - Running setup.py install for mozsystemmonitor 16:30:07 INFO - Successfully installed mozsystemmonitor 16:30:07 INFO - Cleaning up... 16:30:07 INFO - Return code: 0 16:30:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:07 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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:30:07 INFO - Using env: {'DISPLAY': ':0', 16:30:07 INFO - 'HOME': '/home/cltbld', 16:30:07 INFO - 'LANG': 'en_US.UTF-8', 16:30:07 INFO - 'LANGUAGE': 'en_US:en', 16:30:07 INFO - 'LOGNAME': 'cltbld', 16:30:07 INFO - 'MAIL': '/var/mail/cltbld', 16:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:07 INFO - 'MOZ_NO_REMOTE': '1', 16:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:07 INFO - 'NO_EM_RESTART': '1', 16:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:07 INFO - 'PWD': '/builds/slave/test', 16:30:07 INFO - 'SHELL': '/bin/bash', 16:30:07 INFO - 'SHLVL': '1', 16:30:07 INFO - 'TERM': 'linux', 16:30:07 INFO - 'TMOUT': '86400', 16:30:07 INFO - 'USER': 'cltbld', 16:30:07 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:07 INFO - '_': '/tools/buildbot/bin/python'} 16:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:07 INFO - Downloading/unpacking jsonschema==2.5.1 16:30: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 16:30: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 16:30: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 16:30: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 16:30:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:30:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:09 INFO - Downloading functools32-3.2.3-2.tar.gz 16:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:30:09 INFO - warning: no files found matching '*.txt' 16:30:09 INFO - no previously-included directories found matching 'build' 16:30:09 INFO - no previously-included directories found matching 'dist' 16:30:09 INFO - no previously-included directories found matching '.git*' 16:30:09 INFO - Installing collected packages: jsonschema, functools32 16:30:09 INFO - Running setup.py install for functools32 16:30:09 INFO - warning: no files found matching '*.txt' 16:30:09 INFO - no previously-included directories found matching 'build' 16:30:09 INFO - no previously-included directories found matching 'dist' 16:30:09 INFO - no previously-included directories found matching '.git*' 16:30:09 INFO - Successfully installed jsonschema functools32 16:30:09 INFO - Cleaning up... 16:30:09 INFO - Return code: 0 16:30:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:09 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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:30:09 INFO - Using env: {'DISPLAY': ':0', 16:30:09 INFO - 'HOME': '/home/cltbld', 16:30:09 INFO - 'LANG': 'en_US.UTF-8', 16:30:09 INFO - 'LANGUAGE': 'en_US:en', 16:30:09 INFO - 'LOGNAME': 'cltbld', 16:30:09 INFO - 'MAIL': '/var/mail/cltbld', 16:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:09 INFO - 'MOZ_NO_REMOTE': '1', 16:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:09 INFO - 'NO_EM_RESTART': '1', 16:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:09 INFO - 'PWD': '/builds/slave/test', 16:30:09 INFO - 'SHELL': '/bin/bash', 16:30:09 INFO - 'SHLVL': '1', 16:30:09 INFO - 'TERM': 'linux', 16:30:09 INFO - 'TMOUT': '86400', 16:30:09 INFO - 'USER': 'cltbld', 16:30:09 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:09 INFO - '_': '/tools/buildbot/bin/python'} 16:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:30:09 INFO - Cleaning up... 16:30:09 INFO - Return code: 0 16:30:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:09 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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:30:09 INFO - Using env: {'DISPLAY': ':0', 16:30:09 INFO - 'HOME': '/home/cltbld', 16:30:09 INFO - 'LANG': 'en_US.UTF-8', 16:30:09 INFO - 'LANGUAGE': 'en_US:en', 16:30:09 INFO - 'LOGNAME': 'cltbld', 16:30:09 INFO - 'MAIL': '/var/mail/cltbld', 16:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:09 INFO - 'MOZ_NO_REMOTE': '1', 16:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:09 INFO - 'NO_EM_RESTART': '1', 16:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:09 INFO - 'PWD': '/builds/slave/test', 16:30:09 INFO - 'SHELL': '/bin/bash', 16:30:09 INFO - 'SHLVL': '1', 16:30:09 INFO - 'TERM': 'linux', 16:30:09 INFO - 'TMOUT': '86400', 16:30:09 INFO - 'USER': 'cltbld', 16:30:09 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:09 INFO - '_': '/tools/buildbot/bin/python'} 16:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:10 INFO - Downloading blobuploader-1.2.4.tar.gz 16:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:30:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:30:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:11 INFO - Downloading docopt-0.6.1.tar.gz 16:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:30:11 INFO - Installing collected packages: blobuploader, requests, docopt 16:30:11 INFO - Running setup.py install for blobuploader 16:30:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:30:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:30:11 INFO - Running setup.py install for requests 16:30:11 INFO - Running setup.py install for docopt 16:30:12 INFO - Successfully installed blobuploader requests docopt 16:30:12 INFO - Cleaning up... 16:30:12 INFO - Return code: 0 16:30:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:12 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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:30:12 INFO - Using env: {'DISPLAY': ':0', 16:30:12 INFO - 'HOME': '/home/cltbld', 16:30:12 INFO - 'LANG': 'en_US.UTF-8', 16:30:12 INFO - 'LANGUAGE': 'en_US:en', 16:30:12 INFO - 'LOGNAME': 'cltbld', 16:30:12 INFO - 'MAIL': '/var/mail/cltbld', 16:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:12 INFO - 'MOZ_NO_REMOTE': '1', 16:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:12 INFO - 'NO_EM_RESTART': '1', 16:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:12 INFO - 'PWD': '/builds/slave/test', 16:30:12 INFO - 'SHELL': '/bin/bash', 16:30:12 INFO - 'SHLVL': '1', 16:30:12 INFO - 'TERM': 'linux', 16:30:12 INFO - 'TMOUT': '86400', 16:30:12 INFO - 'USER': 'cltbld', 16:30:12 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:12 INFO - '_': '/tools/buildbot/bin/python'} 16:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:12 INFO - Running setup.py (path:/tmp/pip-qITVIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:12 INFO - Running setup.py (path:/tmp/pip-k8qkRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:12 INFO - Running setup.py (path:/tmp/pip-9pU2pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:12 INFO - Running setup.py (path:/tmp/pip-JxvGZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:12 INFO - Running setup.py (path:/tmp/pip-dNBAcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:12 INFO - Running setup.py (path:/tmp/pip-w0UWv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:12 INFO - Running setup.py (path:/tmp/pip-K8fp0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:12 INFO - Running setup.py (path:/tmp/pip-kLfSBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:12 INFO - Running setup.py (path:/tmp/pip-A451TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:12 INFO - Running setup.py (path:/tmp/pip-6E64jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:13 INFO - Running setup.py (path:/tmp/pip-pamfiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:13 INFO - Running setup.py (path:/tmp/pip-XDL_mP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:13 INFO - Running setup.py (path:/tmp/pip-xQ0ioe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:13 INFO - Running setup.py (path:/tmp/pip-9Ihu3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:13 INFO - Running setup.py (path:/tmp/pip-N8Do4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:13 INFO - Running setup.py (path:/tmp/pip-nrA88V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:13 INFO - Running setup.py (path:/tmp/pip-jZ1YRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:30:13 INFO - Running setup.py install for manifestparser 16:30:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:30:13 INFO - Running setup.py install for mozcrash 16:30:13 INFO - Running setup.py install for mozdebug 16:30:13 INFO - Running setup.py install for mozdevice 16:30:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:30:13 INFO - Running setup.py install for mozfile 16:30:14 INFO - Running setup.py install for mozhttpd 16:30:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for mozinfo 16:30:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for mozInstall 16:30:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for mozleak 16:30:14 INFO - Running setup.py install for mozlog 16:30:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for moznetwork 16:30:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for mozprocess 16:30:14 INFO - Running setup.py install for mozprofile 16:30:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for mozrunner 16:30:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:30:14 INFO - Running setup.py install for mozscreenshot 16:30:15 INFO - Running setup.py install for moztest 16:30:15 INFO - Running setup.py install for mozversion 16:30:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:30:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:30:15 INFO - Cleaning up... 16:30:15 INFO - Return code: 0 16:30:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:15 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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:30:15 INFO - Using env: {'DISPLAY': ':0', 16:30:15 INFO - 'HOME': '/home/cltbld', 16:30:15 INFO - 'LANG': 'en_US.UTF-8', 16:30:15 INFO - 'LANGUAGE': 'en_US:en', 16:30:15 INFO - 'LOGNAME': 'cltbld', 16:30:15 INFO - 'MAIL': '/var/mail/cltbld', 16:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:15 INFO - 'MOZ_NO_REMOTE': '1', 16:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:15 INFO - 'NO_EM_RESTART': '1', 16:30:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:15 INFO - 'PWD': '/builds/slave/test', 16:30:15 INFO - 'SHELL': '/bin/bash', 16:30:15 INFO - 'SHLVL': '1', 16:30:15 INFO - 'TERM': 'linux', 16:30:15 INFO - 'TMOUT': '86400', 16:30:15 INFO - 'USER': 'cltbld', 16:30:15 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:15 INFO - '_': '/tools/buildbot/bin/python'} 16:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:15 INFO - Running setup.py (path:/tmp/pip-6CK9t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:15 INFO - Running setup.py (path:/tmp/pip-YoZvTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:15 INFO - Running setup.py (path:/tmp/pip-0H5uwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:15 INFO - Running setup.py (path:/tmp/pip-csdRcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:15 INFO - Running setup.py (path:/tmp/pip-wcg6hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:15 INFO - Running setup.py (path:/tmp/pip-VCtahP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:15 INFO - Running setup.py (path:/tmp/pip-070FGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:16 INFO - Running setup.py (path:/tmp/pip-q2HXRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:16 INFO - Running setup.py (path:/tmp/pip-v5IUku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:16 INFO - Running setup.py (path:/tmp/pip-Zk8QKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:16 INFO - Running setup.py (path:/tmp/pip-UZZGmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:16 INFO - Running setup.py (path:/tmp/pip-fDiCr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:16 INFO - Running setup.py (path:/tmp/pip-RR4amb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:16 INFO - Running setup.py (path:/tmp/pip-thqLBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:16 INFO - Running setup.py (path:/tmp/pip-p748bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:16 INFO - Running setup.py (path:/tmp/pip-Iyvi16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:16 INFO - Running setup.py (path:/tmp/pip-2hiuC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:30:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:17 INFO - Downloading blessings-1.6.tar.gz 16:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:30:17 INFO - Installing collected packages: blessings 16:30:17 INFO - Running setup.py install for blessings 16:30:17 INFO - Successfully installed blessings 16:30:17 INFO - Cleaning up... 16:30:18 INFO - Return code: 0 16:30:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:30:18 INFO - Reading from file tmpfile_stdout 16:30:18 INFO - Current package versions: 16:30:18 INFO - argparse == 1.2.1 16:30:18 INFO - blessings == 1.6 16:30:18 INFO - blobuploader == 1.2.4 16:30:18 INFO - docopt == 0.6.1 16:30:18 INFO - functools32 == 3.2.3-2 16:30:18 INFO - jsonschema == 2.5.1 16:30:18 INFO - manifestparser == 1.1 16:30:18 INFO - mozInstall == 1.13 16:30:18 INFO - mozcrash == 1.0 16:30:18 INFO - mozdebug == 0.1 16:30:18 INFO - mozdevice == 0.51 16:30:18 INFO - mozfile == 1.2 16:30:18 INFO - mozhttpd == 0.7 16:30:18 INFO - mozinfo == 0.10 16:30:18 INFO - mozleak == 0.1 16:30:18 INFO - mozlog == 3.5 16:30:18 INFO - moznetwork == 0.27 16:30:18 INFO - mozprocess == 0.25 16:30:18 INFO - mozprofile == 0.28 16:30:18 INFO - mozrunner == 6.13 16:30:18 INFO - mozscreenshot == 0.1 16:30:18 INFO - mozsystemmonitor == 0.3 16:30:18 INFO - moztest == 0.8 16:30:18 INFO - mozversion == 1.4 16:30:18 INFO - psutil == 3.1.1 16:30:18 INFO - requests == 1.2.3 16:30:18 INFO - wsgiref == 0.1.2 16:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30: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')]} 16:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:18 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 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:30:18 INFO - Using env: {'DISPLAY': ':0', 16:30:18 INFO - 'HOME': '/home/cltbld', 16:30:18 INFO - 'LANG': 'en_US.UTF-8', 16:30:18 INFO - 'LANGUAGE': 'en_US:en', 16:30:18 INFO - 'LOGNAME': 'cltbld', 16:30:18 INFO - 'MAIL': '/var/mail/cltbld', 16:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:18 INFO - 'MOZ_NO_REMOTE': '1', 16:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:18 INFO - 'NO_EM_RESTART': '1', 16:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:18 INFO - 'PWD': '/builds/slave/test', 16:30:18 INFO - 'SHELL': '/bin/bash', 16:30:18 INFO - 'SHLVL': '1', 16:30:18 INFO - 'TERM': 'linux', 16:30:18 INFO - 'TMOUT': '86400', 16:30:18 INFO - 'USER': 'cltbld', 16:30:18 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:18 INFO - '_': '/tools/buildbot/bin/python'} 16:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:30:18 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:30:19 INFO - Installing collected packages: simplejson 16:30:19 INFO - Running setup.py install for simplejson 16:30:19 INFO - building 'simplejson._speedups' extension 16:30:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:30:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:30:20 INFO - Successfully installed simplejson 16:30:20 INFO - Cleaning up... 16:30:20 INFO - Return code: 0 16:30:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2207a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23aeb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, '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': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:30:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:30:20 INFO - Using env: {'DISPLAY': ':0', 16:30:20 INFO - 'HOME': '/home/cltbld', 16:30:20 INFO - 'LANG': 'en_US.UTF-8', 16:30:20 INFO - 'LANGUAGE': 'en_US:en', 16:30:20 INFO - 'LOGNAME': 'cltbld', 16:30:20 INFO - 'MAIL': '/var/mail/cltbld', 16:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:20 INFO - 'MOZ_NO_REMOTE': '1', 16:30:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:20 INFO - 'NO_EM_RESTART': '1', 16:30:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:20 INFO - 'PWD': '/builds/slave/test', 16:30:20 INFO - 'SHELL': '/bin/bash', 16:30:20 INFO - 'SHLVL': '1', 16:30:20 INFO - 'TERM': 'linux', 16:30:20 INFO - 'TMOUT': '86400', 16:30:20 INFO - 'USER': 'cltbld', 16:30:20 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:20 INFO - '_': '/tools/buildbot/bin/python'} 16:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:30:20 INFO - Cleaning up... 16:30:20 INFO - Return code: 0 16:30:20 INFO - Running post-action listener: _resource_record_post_action 16:30:20 INFO - Running post-action listener: _start_resource_monitoring 16:30:20 INFO - Starting resource monitoring. 16:30:20 INFO - [mozharness: 2017-08-29 23:30:20.960829Z] Finished create-virtualenv step (success) 16:30:20 INFO - [mozharness: 2017-08-29 23:30:20.961347Z] Running install step. 16:30:20 INFO - Running pre-action listener: _resource_record_pre_action 16:30:20 INFO - Running main action method: install 16:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:30:21 INFO - Reading from file tmpfile_stdout 16:30:21 INFO - Detecting whether we're running mozinstall >=1.0... 16:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:30:21 INFO - Reading from file tmpfile_stdout 16:30:21 INFO - Output received: 16:30:21 INFO - Usage: mozinstall [options] installer 16:30:21 INFO - Options: 16:30:21 INFO - -h, --help show this help message and exit 16:30:21 INFO - -d DEST, --destination=DEST 16:30:21 INFO - Directory to install application into. [default: 16:30:21 INFO - "/builds/slave/test"] 16:30:21 INFO - --app=APP Application being installed. [default: firefox] 16:30:21 INFO - mkdir: /builds/slave/test/build/application 16:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:30:37 INFO - Reading from file tmpfile_stdout 16:30:37 INFO - Output received: 16:30:37 INFO - /builds/slave/test/build/application/firefox/firefox 16:30:37 INFO - Running post-action listener: _resource_record_post_action 16:30:37 INFO - [mozharness: 2017-08-29 23:30:37.804002Z] Finished install step (success) 16:30:37 INFO - [mozharness: 2017-08-29 23:30:37.804252Z] Running setup-mitmproxy step. 16:30:37 INFO - Running pre-action listener: _resource_record_pre_action 16:30:37 INFO - Running main action method: setup_mitmproxy 16:30:37 INFO - Skipping: mitmproxy is not required 16:30:37 INFO - Running post-action listener: _resource_record_post_action 16:30:37 INFO - [mozharness: 2017-08-29 23:30:37.805365Z] Finished setup-mitmproxy step (success) 16:30:37 INFO - [mozharness: 2017-08-29 23:30:37.805584Z] Running run-tests step. 16:30:37 INFO - Running pre-action listener: _resource_record_pre_action 16:30:37 INFO - Running pre-action listener: _set_gcov_prefix 16:30:37 INFO - Running main action method: run_tests 16:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:30:37 INFO - Python 2.7.3 16:30:37 INFO - Return code: 0 16:30:37 INFO - grabbing minidump binary from tooltool 16:30: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')]} 16:30:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x23ab580>, '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 0x23ac480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ac910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:30:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:30:37 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:30:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:30:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:37 INFO - Return code: 0 16:30:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:30:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:30:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:30:37 INFO - ENV: RUST_BACKTRACE is now full 16:30:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:30:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:30: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/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:30: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/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:30:37 INFO - Using env: {'DISPLAY': ':0', 16:30:37 INFO - 'HOME': '/home/cltbld', 16:30:37 INFO - 'LANG': 'en_US.UTF-8', 16:30:37 INFO - 'LANGUAGE': 'en_US:en', 16:30:37 INFO - 'LOGNAME': 'cltbld', 16:30:37 INFO - 'MAIL': '/var/mail/cltbld', 16:30:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:37 INFO - 'MOZ_NO_REMOTE': '1', 16:30:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:37 INFO - 'NO_EM_RESTART': '1', 16:30:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:37 INFO - 'PWD': '/builds/slave/test', 16:30:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:37 INFO - 'RUST_BACKTRACE': 'full', 16:30:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:30:37 INFO - 'SHELL': '/bin/bash', 16:30:37 INFO - 'SHLVL': '1', 16:30:37 INFO - 'STYLO_FORCE_ENABLED': '1', 16:30:37 INFO - 'STYLO_THREADS': '1', 16:30:37 INFO - 'TERM': 'linux', 16:30:37 INFO - 'TMOUT': '86400', 16:30:37 INFO - 'USER': 'cltbld', 16:30:37 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:37 INFO - '_': '/tools/buildbot/bin/python'} 16:30: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/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:30:38 INFO - mozversion application_buildid: 20170829223806 16:30:38 INFO - mozversion application_changeset: 8f221407c6006ac1de529da77ecc18431dde3e99 16:30:38 INFO - mozversion application_display_name: Nightly 16:30:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:38 INFO - mozversion application_name: Firefox 16:30:38 INFO - mozversion application_remotingname: firefox 16:30:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:30:38 INFO - mozversion application_vendor: Mozilla 16:30:38 INFO - mozversion application_version: 57.0a1 16:30:38 INFO - mozversion platform_buildid: 20170829223806 16:30:38 INFO - mozversion platform_changeset: 8f221407c6006ac1de529da77ecc18431dde3e99 16:30:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:30:38 INFO - mozversion platform_version: 57.0a1 16:30:38 INFO - using testdate: 1504049438 16:30:38 INFO - actual date: 1504049438 16:30:38 INFO - starting webserver on 'localhost:46991' 16:30:38 INFO - SUITE-START | Running 2 tests 16:30:38 INFO - TEST-START | dromaeo_css 16:30:38 INFO - Initialising browser for dromaeo_css test... 16:30:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46991/getInfo.html -profile /tmp/tmp5zY42J/profile 16:30:38 INFO - TEST-INFO | started process 21432 (/builds/slave/test/build/application/firefox/firefox http://localhost:46991/getInfo.html) 16:30:43 INFO - TEST-INFO | 21432: exit 0 16:30:44 INFO - Browser initialized. 16:30:44 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:44 INFO - Using env: {'DISPLAY': ':0', 16:30:44 INFO - 'HOME': '/home/cltbld', 16:30:44 INFO - 'JSGC_DISABLE_POISONING': '1', 16:30:44 INFO - 'LANG': 'en_US.UTF-8', 16:30:44 INFO - 'LANGUAGE': 'en_US:en', 16:30:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:30:44 INFO - 'LOGNAME': 'cltbld', 16:30:44 INFO - 'MAIL': '/var/mail/cltbld', 16:30:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:30:44 INFO - 'MOZ_CRASHREPORTER': '1', 16:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:30:44 INFO - 'MOZ_NO_REMOTE': '1', 16:30:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:44 INFO - 'NO_EM_RESTART': '1', 16:30:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:44 INFO - 'PWD': '/builds/slave/test', 16:30:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:44 INFO - 'RUST_BACKTRACE': 'full', 16:30:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:30:44 INFO - 'SHELL': '/bin/bash', 16:30:44 INFO - 'SHLVL': '1', 16:30:44 INFO - 'STYLO_FORCE_ENABLED': '1', 16:30:44 INFO - 'STYLO_THREADS': '1', 16:30:44 INFO - 'TERM': 'linux', 16:30:44 INFO - 'TMOUT': '86400', 16:30:44 INFO - 'USER': 'cltbld', 16:30:44 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:44 INFO - '_': '/tools/buildbot/bin/python'} 16:30:44 INFO - TEST-INFO | started process 21619 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5zY42J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:44 INFO - PID 21619 | 16:30:44 INFO - PID 21619 | (/builds/slave/test/build/application/firefox/firefox:21670): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:30:44 INFO - PID 21619 | 16:30:55 INFO - PID 21619 | RSS: Main: 167497728 16:30:55 INFO - PID 21619 | 16:33:54 INFO - PID 21619 | Cycle 1(1): loaded http://localhost:46991/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46991/tests/dromaeo/cssquery-ext.html) 16:33:55 INFO - PID 21619 | RSS: Main: 174166016 16:33:55 INFO - PID 21619 | 16:36:56 INFO - PID 21619 | Cycle 1(1): loaded http://localhost:46991/tests/dromaeo/cssquery-ext.html (next: http://localhost:46991/tests/dromaeo/cssquery-jquery.html) 16:36:57 INFO - PID 21619 | RSS: Main: 170217472 16:36:57 INFO - PID 21619 | 16:39:59 INFO - PID 21619 | Cycle 1(1): loaded http://localhost:46991/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46991/tests/dromaeo/cssquery-mootools.html) 16:39:59 INFO - PID 21619 | RSS: Main: 171974656 16:39:59 INFO - PID 21619 | 16:43:01 INFO - PID 21619 | Cycle 1(1): loaded http://localhost:46991/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46991/tests/dromaeo/cssquery-prototype.html) 16:43:02 INFO - PID 21619 | RSS: Main: 171270144 16:43:02 INFO - PID 21619 | 16:46:05 INFO - PID 21619 | Cycle 1(1): loaded http://localhost:46991/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46991/tests/dromaeo/cssquery-yui.html) 16:46:06 INFO - PID 21619 | RSS: Main: 172445696 16:46:06 INFO - PID 21619 | 16:49:07 INFO - PID 21619 | Cycle 1(1): loaded http://localhost:46991/tests/dromaeo/cssquery-yui.html (next: http://localhost:46991/tests/dromaeo/cssquery-dojo.html) 16:49:08 INFO - PID 21619 | RSS: Main: 174530560 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | __start_tp_report 16:49:08 INFO - PID 21619 | _x_x_mozilla_page_load 16:49:08 INFO - PID 21619 | _x_x_mozilla_page_load_details 16:49:08 INFO - PID 21619 | |i|pagename|runs| 16:49:08 INFO - PID 21619 | |0;dojo.html;3306.206510437494;3306.785058971165;3313.78460400074;3351.346487434951;3358.0261724100014;6242.1885154929905;6279.874402511953;6290.433860952513;6348.016057511081;6375.936240637604;6124.958757011308;6238.220222472191;6251.2498500180045;6291.40231677991;6297.149884765556;5811.6803575803315;5817.243758311435;5837.620554663955;5840.853978650525;5902.2622172228475;5653.575981801361;5775.393583673716;5859.736311865955;5917.497012753921;5935.88128237435;5438.782448702067;5784.392638772911;5847.532197424196;5861.589688721791;5883.382244864313;5786.247787787561;5811.360750317462;5877.647341159502;5893.764249430018;5898.793542226044;5723.427657234285;5727.570432217601;5808.506276966463;5810.1865738796605;5815.098659707752;6243.219597550306;6279.654618995954;6292.056191571301;6313.905291420632;6330.87338253237;6698.799036028927;6716.93283067168;6778.288279730656;6791.898121528181;6795.830104247384;6788.7963361098755;6871.896921546181;6886.208086070116;6900.309969003059;6907.309269073053;6303.684815759239;6307.810765677037;6378;6384.170057892445;6397.648129352884;6937.063496427918;6940.028396024561;6953.756618518328;7023.086998690138;7065.187503437068;6689.397954184147;6694.866102677919;6698.22970358415;6698.83252918681;6735.831604209934;6755.662216889136;6770.052192693027;6807.65961701913;6831.316868313129;6835.179778426521;6697.832554186087;6719.092922455406;6723.193216813916;6800.047993280945;6808.455323574102;6505.251896032017;6716.664166791738;6724.226713927961;6802.965985170043;6808.8978665320055;5394.3796463406425;5399.217113518596;5406.783728650888;5409.215663728737;5412.377576578744;4679.859604211879;4688.859334219979;4733.195356789354;4759.857204283877;4796.952030479721;5852.970735146382;5874.118882167709;5877.059670452707;5952.4047595241;5955.67243801595;6838.418734407532;6838.589684618939;6860.862782744417;6878.965605172042;6887.104676392037;5891.793787217427;5912.438318359749;5947.37552556984;5980.37206093354;6008.939910600925;5715.114157305624;5780.104083867007;5831.737571809279;5905.793297234576;5913.526917846562;6507.023946407982;6619.271880093186;6649.534532582769;6666.566673166229;6670.199576050808;6766.951122576009;6769.93230067693;6850.828729281808;6870.690818913261;6871.4502839773695;6535.44448721864;6539;6615.6030638161865;6628.237752658506;6641.738069766922;6560.212774466903;6570;6627.469802415699;6649.102371179829;6723.596584204964;6698.5980841149685;6701.061851340914;6781.864362712619;6783.287754785873;6783.423409010289;6456.451201647913;6469.45009674183;6548;6555.704993275219;6565.671716414065;6475;6477.643729594729;6644.335566443317;6653;6658.700358483977;6528.37980391862;6530.869382612416;6600.339966003553;6619.3049729779605;6621.33786621334;6583.868322633616;6603.306652801579;6609.570377925419;6660.666966651745;6664.600123992576;6394.072859435263;6447.645379503984;6471.288158302489;6474.417302442803;6475.158229430144 16:49:08 INFO - PID 21619 | |1;ext.html;811.128037359838;860.3203469259284;880.3529405886673;881.8104107616861;1122.0798944865214;29392.41215175698;29864;37653.81173094134;38909;38929.610703892955;40086.599134008655;40299.19401611966;40466.19067618646;40517.594824051754;40568.17443215055;7089.89365159523;7585.962070189655;7700.4224683148705;7704.036995375578;7705.036870391201;7507;7617.428692848031;7621.428392870529;7622.2758837910305;7623.085229772435;7592.620368981557;7658.655360508763;7666.846663066735;7670.654820533062;7678.616069196546;7636.618169091579;7637.809054773619;7643.42674299427;7676.7313144040245;7681.615919204045;7526.73656422028;7571.091469023752;7573.697052117908;7577.886331705029;7578.317951384347;35642;35665.82167089174;35785.642143578494;35880.64119358799;35919.28161436783;3511.631278715749;3524.259905419865;3528.7177025838128;3551.3074950384685;3552.378333791602;29936;30107.849460752775;30265.546016809767;30432.391352173054;30475.847620761975;5110.233464980282;5133.30700355451;5217.269582258449;5221.086309895791;5236.24074509194;183927;184160;184860;185060;185335;45545.316820247725;46000.76999615048;46052.3092153618;46215.075698485845;46244.07511849811;46605.76697116493;46968.295475567895;47059;47130.5286947133;47185.05629887383;13804.654883627989;15650;15875.52373428799;15893.125878076586;15930.123843188505;11646.24299420535;13058.34708264583;13096.14875033139;13307.800382994263;13311.73376532464;13829.446822127002;13970.580882573538;14050.508232212029;14086.859131408759;14180.574582762534;5650.74571644277;5678.5741069419955;5678.772849086073;5810.50610698087;5890.558208134407;1009.0414106598761;1016.8779746430475;1019.2712210769188;1034.177828626244;1035.9741006474749;22447.21434749776;22536;22636.094556217897;22716.637001779945;23036.76963230346;1112.3048094940661;1118.2451845004753;1128.6952522818792;1133.9262947908524;1134.6766171641043;12214.938925305316;15338;15430.922845385701;15598.010208409132;16121.274542645375;234433;235708;236216;237014;237431;53283.73358133184;53310.46689533132;53483.732581336844;53705.462945370826;53710.73144634252;1445.5663301009656;1460.160407765518;1478.778183272496;1481.799957005812;1481.8444063373406;25691.100811472257;26749.866250668623;27388.45223095487;27695.58456623112;28033.299167520974;49132.26301605407;49561.25658115203;49578.50421495883;49604.25593616026;49745.75127124341;212049;214739;215949;216698;216953;33270.334593308486;35000.64999350076;35025.299494009465;35080.64919350876;35247.647523524436;42051;42573.5742642582;42585.78707106445;42686;42732.35901461419;31537.36925261436;31682.841585792845;32127.518087228244;32373.838130809196;32541.349173015933;31153.532697010018;31163.688363116078;31296;31424.52863207008;31620.36759264848;35440.29119417649;35450.29099417946;35796;35839.46240806442;35917.28165436624;30401;30757.53863692091;30947;30958.53562196614;31354.52968205433;22919.197828075936;23166.536669266858;23185.188518401774;23392.415189619715;23979.28062158138 16:49:08 INFO - PID 21619 | |2;jquery.html;547.4087984976226;552.8396764938167;557.9469950354714;561.4469747298909;565.1043096691743;21742.89128554357;22574.20990265341;22600.20899268526;22669.206577769783;22691.319260422173;26006.869965650192;26631.467370652575;26708.59937100945;26733.598996015025;26748.59877101845;6504.869902601945;6622.238442579117;6637.1703537057865;6643.269240383554;6645.102911107011;5983.1623572699855;6517.315681853408;6588.4729221662155;6589.275179730226;6660.034295027218;6579.703913323888;6588.571742836724;6597.538172327939;6599.043138744879;6600.042993765901;6595.901061484057;6600.537962342638;6602.471802255808;6603.438707709851;6606.372394622527;6124.479419249369;6126.050462178369;6128.662923539204;6128.938710612926;6134.447899689049;28711;29321.41357172866;29825.25436864074;29900.70099299001;29927.551086733718;2103.347962131744;2141.2719675310314;2141.967870481944;2142.0360837623134;2142.6143294207036;18940.621187576307;19659.80340196594;19670.114844831875;19675.40973770789;19684.01579920998;2330.3940975346363;2356.646503024543;2393.676853624756;2423.9636405453934;2440;137788.31105844508;140620;140708.29645851807;140790;140792;13940.30298485092;14810;14899.851001489846;15173.165475898712;15342.693146137239;23116.884415577813;23155.3053408398;23199;23231.419214519436;23248;10023.448710321003;10434.843477347846;10443;10494;10522.789544209072;8628.525431101225;9814.116729494379;9858.901410985942;9876.802463950824;9997.100260976547;8076.71731489395;8932.240759535513;9165.266778657691;9245.474496707968;9263.073692630818;2531.152064058524;2570.3935040599627;2578.742125787444;2677.129932771818;2685.9731402686116;956.3114557518714;973.0464145137804;981.6417007792104;988.5057471264367;1006.6325791086347;19794;20253.493662658257;20498.487537811674;23809.904312910585;25123;1025.5436330832856;1051.5215576912487;1064.669952314785;1071.694567048403;1076.6769969009265;11498.94250528742;11719.472623731783;11726.589569365031;11770.588029419101;11811.645650630495;554537;554936;555598;570378;572723;115976;117268;117324.41337793256;120611.39694301647;124063;1243.452880732493;1243.875612438749;1273.9044571656977;1275.196626125535;1285.029802499126;18809.24763009519;18889.433317000512;19020.809791901906;19025.619487610446;19284.325048623232;58984.70507647434;59688;59996.700016499635;60173;61874.21566029629;73836.63081684773;74186.25813741793;74768.62615686886;75359.24640753522;75425;12316.137870349348;12957.22256664603;14115.788263175855;14634.634134146732;14963.326650300545;22939.311820645406;23225.651615226125;23644.763552364257;23870.880645596662;23897.641535376635;18691.345802896834;18968.146433410806;19004.619907601493;19062.809371906103;19147.61704765925;16507.174641267648;17083;17166.141692915553;17317.74023389625;17543.298268069644;18014.459566213034;19303.131359089155;19338.22647094157;19487.02564871779;19569.902150489157;14422.567322980327;14473.78289325682;14514.709705805613;14517.419303227753;14585.124892506483;18583.25666973306;18634.813651863307;18959.14683839203;18961.14674839608;18980.240790368232 16:49:08 INFO - PID 21619 | |3;mootools.html;1321.6497628128545;1378.8207533020711;1393.3799459240643;1393.9163650180988;1397.3502321420538;14242.786358204636;14556;14854.702905941887;14866.925665371686;14870.107793532396;15292.923535382308;15546;15551.844481555181;15556.377744890218;15570.065796052244;2684.7717943974785;2714.24001279642;2715.158300926709;2715.429759750445;2723.8093333466663;2437.549053425122;2510.535550923085;2571.832830865997;2572.9228123156336;2575.665163528748;2768.5570308750603;2788.8745006474755;2821.675507316664;2825.6326677531893;2826.4488424757183;2748.738869807365;2769.7645700115413;2772.8613569321556;2778.333200031988;2779.6664400271893;2690.1391554702504;2699.5545734953707;2701.9189424317105;2703.3917368592106;2711.64748582685;14322.928385358007;14615.63460913475;14615.78076328856;14619.63450913725;14633.268336583234;7708.768736937901;7848.7645370638975;8219.589020548949;8228.83542329156;8228.958855205745;5327.227552004976;5456.48163424474;5609.27079479665;5611.270534830446;5618.831435056954;1926.4317026477222;1945.3774792066545;1962.8920409377479;1964.5481539245911;1979.4556496963307;24821;25089.12188073427;25110.623340649905;25146.11988580409;25190.622140667903;6527.771527996498;6693.397594216446;6694.25151470369;6853.7943861684225;6873.175218973755;16157.757633635505;16180.838191617933;16210.594735131715;16230.51308460748;16247.67504649924;7694.499857509356;7845.529268243924;7873.370130389555;7873.960630196812;7876.4092693047105;7145.82135446618;7250.782476525713;7338.816529586803;7387.039684840936;7403.740869069665;5125.1799712045895;5293.179557168645;5335.439778823245;5348.705821179872;5362.061639213122;1542.3522120709329;1542.8611424971807;1552.1463195242573;1559.3216950626513;1573.9842601574067;884.920357167845;908.8909330880336;909.3089252168268;920.2132176988712;922.1378011559214;7437.219091995371;7463.253674632493;7519.586422746802;7545.698172073166;7559.244075592506;706.8904319830434;722.7073035420663;978.7161723100363;997.81540415022;1001.5442973447064;4745.715257084586;5158.226266060046;5178.300929374562;5300.787968481218;5378.650387724785;19500.122494487783;19894.403167904988;20150.798492015187;20373.694394584094;20418.48953776138;20328.390148295573;20418.69371959422;20505.79494205069;20528.794712052986;20706.48233794167;10811.35131892089;11134.387608681438;11348.319100853974;11359.545618175345;11520.423978801027;8074.232947869943;8231.794205144919;8322;8364.289035432057;8450.732390141528;17386.652266954843;17866.821331787036;18069.096545172426;18156.909215454365;18335.26658933681;17766.822331776515;17842.55393615118;17969.460916172535;18145.364912228008;18388.632227355647;9989.500524973868;10210.234232432449;10218.897811022092;10227.590896364061;10231;12936.547220847226;13051.804222936473;13100.475980961037;13113.475460981836;13208.735825283633;10510.737231569272;10706.250562460553;10723.731906702395;10749.355038697704;10931.890681093088;10556.472176390997;10619.15046796239;10749.140068794633;10753.193510486588;10835.837462437912;8849.115088491357;8941.821163576822;8943.463392196489;8964.417312874642;8970.37207395476;7001.649917504206;7150.356467917913;7255.056842610426;7480.588567628833;7516.887246691414;6685.164354455693;6725.49558783103;6736.6968486417245;6741.359570840762;6776.932230677828 16:49:08 INFO - PID 21619 | |4;prototype.html;493.8419705694178;496.5655051829649;502.9949700502995;524.1220954900541;525.1571228178773;6322.209723784527;6361;6436.77471288505;6440;6499.967500162505;6223.875522489548;6268.341824108471;6299.716512756925;6357.823802596523;6388.029566251692;4922.286268491067;4965.329680493123;5134.383873935114;5180.715060671661;5268.130758424855;4958.305837182797;5014.52362025607;5051.696898186102;5055.090083784918;5055.115354812911;4924.28597853311;4934.97532512337;4953.257011448293;4965.088440950224;5002.199648056312;4903.509649035103;4932.5807306379;4979.576735977446;4982.202847544376;4997.900041999176;4457.910841783146;4458.77706114693;4494.640428765723;4502.864914052584;4511.639068874482;6131.754729810802;6203.844903877394;6243.5005199584375;6261.812145635638;6270.09083682868;5696.629719068247;5705.115707065412;5707.6290041147195;5724.82641058582;5729.828105156852;6600.405963463263;6613.908705063659;6618.7021584028835;6686.866262674768;6700.966495167493;4793.664443488957;4799.496052914428;4847.836519235393;4870.902581948377;4890.608751299887;6707.564008339442;6777.423918966943;6796.830079248059;6886.793396198122;7020.613866237355;4950.876228094255;4960.826371077067;4964.5992495140945;4971.602271818246;4985.102681517362;4988.6009119270375;5026.29631851541;5047;5079.111155547838;5115.7442127893455;5600.943990560124;5643.604947653625;5656.8868622627315;5658.34928983164;5682.715864206856;6575.375339342755;6650.833729156713;6652.401283884474;6660.467162626978;6685.832854178684;1754.1580041580114;1770.6104656975444;1779.7953235378097;1782.269269599458;1785.1252886085983;1152.2164927849144;1193.8030225012776;1203.789336866045;1206.8853458921565;1207.728261141236;5831.970840145772;5863.560232982543;5882.3529411764675;5963.403659634002;5964.343922168558;6702.246741824594;6819.24988251292;6834.624095674686;6862.4853136015;6873.828154296182;5880.882382352329;5882.499937526534;5899.469047785634;5903;5939.752651943054;3561.9287614248087;3584.336897673939;3604.098975256186;3613.837377318027;3664.871729489455;6572.967135164389;6602.39205513829;6606.141201643755;6646.900296495556;6701.731930722814;7024.824379390556;7025.754098606524;7056.223815380304;7064.1876184238445;7141.857162856713;6117.449429551361;6674.399304062657;6686.3313668632745;6743.258241593421;6753.763618273337;6737.764178253738;6763.695633696397;6763.898541521783;6872.553284036521;6883.449324053963;6697.430718388991;6710.932890671031;6787.389134977876;6802.285759995327;6882.449404047564;6551.21385433751;6564.737410503717;6655;6712.865742685021;6714.563553369016;4848.466668666373;5003.1744762115295;5018.272350509229;5047.823326183566;5057.393112826484;6572.901406479003;6636.336366363325;6735.898961515679;6881.965590172017;6922;6394.424501794861;6499.425316076234;6609.603423794588;6639.269680335063;6652.7006284716335;6547.410733034049;6566.143727478371;6593.637349945799;6607.504437167136;6665.766698165541;6494.7077381517;6532.902006469812;6539.542232043711;6542.378474044958;6599.472042236705;6662.333766623299;6665;6731.798046058626;6731.966340168267;6779.525433219627;6357.300696923242;6388.68056597159;6483.902741458787;6488.318726533646;6525.184351956005 16:49:08 INFO - PID 21619 | |5;yui.html;871.4379225399616;882.0914458108151;900.5002223765807;900.5677274908041;905.162724479856;16589.917050414748;17489.212985415656;17545.122743862805;17566.560835979108;21189.258375956844;18837.905810470962;19012.14445349959;19642.508937276576;19768.20927162912;19783.802161978376;919.8114386550775;946.8627049077879;947.4362754161263;949.8860136783595;949.9335046546745;962.2494454325603;986.4968865878418;998.001998001998;1000.9949950250239;1002.1531805624236;1068.2041878800267;1069.1553672598639;1073.618865302818;1073.7798751255973;1074.462768615692;1047.314009323894;1047.6909311752975;1048.3238311289215;1049.2707568240075;1050.8318669012922;775.7672698190577;779.1857508903153;782.9334506566948;784.1844481738984;784.2236186175645;18522.259109635597;18783.812161878344;19978.500537486678;20104.391648668072;20112.597748045104;21077.578448430944;21364;21528.89235553828;21533.46166345854;21913.452163695874;16837.158142092845;17128.14359282031;17232.913835430867;17350.219240134225;17369.652606947915;3698.685611723007;3705.5182826232417;3708.332500149972;3716.145286584074;3724.3296206682785;130332.3483382577;130646;130791;134794;135298.3235083828;16178.919105404397;16551.25519351632;16782.32870685159;16791.58021049459;17077.31690732357;30066.69933300639;30192.54711179334;30200;31113.844430777703;31363.529547056813;8885.733427997171;8961.283097352327;8975.865362019575;8982.730518084469;9002.684906028258;4409.933850992237;4533.116042371706;4615.284630882219;4633.907321853544;4647.6281897448125;5115.820946266937;5240.0567897778;5243.764030618631;5260.316158899319;5266.21006848968;974.8440249560036;989.223459584223;993.2699465892563;993.4734590666958;995.4773743784426;629.6033498895666;633.8130251575797;641.8074577626693;646.0825627608807;658.2430205263889;11600.635815613377;11729.237599556172;11951.940240298743;11963.700907477383;12114.697132571757;635.269440143829;638.6774678787183;646.524804268862;654.7381047581006;670.9597424154566;5644.209810626516;5996.940030599725;6566;6576.638284894281;6589.703463344161;110230;114350.42824785823;114416.42791786154;115191;118521;94289.5285523568;94800.52599736956;95056.52471737735;95750.52124739332;97795;81990;84261.57869210615;90371;90879.54560227157;96427;12030.518779248732;12776.425060872469;12899.226046437243;13004;13014.934925325313;38798.6120138795;40065.799671001456;40101.799491002355;42050.57949420466;42276.36585451159;73615.26384736084;74203.62898185475;75971;76477.61761191158;76961.23038769541;18193.272269109086;18369.081545922916;18398.816011840245;18415.26338946427;18458.907705461388;19840.20639174417;20833.89583052075;21154.153833846474;21340.253091141734;21451.78548214498;18235.908820455814;18839.811601884354;18967.715484268025;19328.226870925006;19586;17580.945143291443;18077.638447231246;18336.358227461973;18363.35728249505;18522.722159167894;17940.19269132866;18083.728744069114;18143.727844082085;18255.361062362754;18549.629007420044;12433.689157771129;12795.552155674506;13462.528811491551;14083;14260.572182834532;14340.928295358874;14913.776293355391;15131.167785771888;15300;15379.23103844781 16:49:08 INFO - PID 21619 | __end_tp_report 16:49:08 INFO - PID 21619 | __start_cc_report 16:49:08 INFO - PID 21619 | _x_x_mozilla_cycle_collect,366 16:49:08 INFO - PID 21619 | __end_cc_report 16:49:08 INFO - PID 21619 | __startTimestamp1504050548058__endTimestamp 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | ------- Summary: start ------- 16:49:08 INFO - PID 21619 | Number of tests: 6 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | [#0] dojo.html Cycles:165 Average:6242.79 Median:6475.16 stddev:720.26 (11.1%) stddev-sans-first:684.64 16:49:08 INFO - PID 21619 | Values: 3306.2 3306.8 3313.8 3351.3 3358.0 6242.2 6279.9 6290.4 6348.0 6375.9 6125.0 6238.2 6251.2 6291.4 6297.1 5811.7 5817.2 5837.6 5840.9 5902.3 5653.6 5775.4 5859.7 5917.5 5935.9 5438.8 5784.4 5847.5 5861.6 5883.4 5786.2 5811.4 5877.6 5893.8 5898.8 5723.4 5727.6 5808.5 5810.2 5815.1 6243.2 6279.7 6292.1 6313.9 6330.9 6698.8 6716.9 6778.3 6791.9 6795.8 6788.8 6871.9 6886.2 6900.3 6907.3 6303.7 6307.8 6378.0 6384.2 6397.6 6937.1 6940.0 6953.8 7023.1 7065.2 6689.4 6694.9 6698.2 6698.8 6735.8 6755.7 6770.1 6807.7 6831.3 6835.2 6697.8 6719.1 6723.2 6800.0 6808.5 6505.3 6716.7 6724.2 6803.0 6808.9 5394.4 5399.2 5406.8 5409.2 5412.4 4679.9 4688.9 4733.2 4759.9 4797.0 5853.0 5874.1 5877.1 5952.4 5955.7 6838.4 6838.6 6860.9 6879.0 6887.1 5891.8 5912.4 5947.4 5980.4 6008.9 5715.1 5780.1 5831.7 5905.8 5913.5 6507.0 6619.3 6649.5 6666.6 6670.2 6767.0 6769.9 6850.8 6870.7 6871.5 6535.4 6539.0 6615.6 6628.2 6641.7 6560.2 6570.0 6627.5 6649.1 6723.6 6698.6 6701.1 6781.9 6783.3 6783.4 6456.5 6469.5 6548.0 6555.7 6565.7 6475.0 6477.6 6644.3 6653.0 6658.7 6528.4 6530.9 6600.3 6619.3 6621.3 6583.9 6603.3 6609.6 6660.7 6664.6 6394.1 6447.6 6471.3 6474.4 6475.2 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | [#1] ext.html Cycles:180 Average:38003.04 Median:35052.97 stddev:55223.64 (157.5%) stddev-sans-first:55307.94 16:49:08 INFO - PID 21619 | Values: 811.1 860.3 880.4 881.8 1122.1 29392.4 29864.0 37653.8 38909.0 38929.6 40086.6 40299.2 40466.2 40517.6 40568.2 7089.9 7586.0 7700.4 7704.0 7705.0 7507.0 7617.4 7621.4 7622.3 7623.1 7592.6 7658.7 7666.8 7670.7 7678.6 7636.6 7637.8 7643.4 7676.7 7681.6 7526.7 7571.1 7573.7 7577.9 7578.3 35642.0 35665.8 35785.6 35880.6 35919.3 3511.6 3524.3 3528.7 3551.3 3552.4 29936.0 30107.8 30265.5 30432.4 30475.8 5110.2 5133.3 5217.3 5221.1 5236.2 183927.0 184160.0 184860.0 185060.0 185335.0 45545.3 46000.8 46052.3 46215.1 46244.1 46605.8 46968.3 47059.0 47130.5 47185.1 13804.7 15650.0 15875.5 15893.1 15930.1 11646.2 13058.3 13096.1 13307.8 13311.7 13829.4 13970.6 14050.5 14086.9 14180.6 5650.7 5678.6 5678.8 5810.5 5890.6 1009.0 1016.9 1019.3 1034.2 1036.0 22447.2 22536.0 22636.1 22716.6 23036.8 1112.3 1118.2 1128.7 1133.9 1134.7 12214.9 15338.0 15430.9 15598.0 16121.3 234433.0 235708.0 236216.0 237014.0 237431.0 53283.7 53310.5 53483.7 53705.5 53710.7 1445.6 1460.2 1478.8 1481.8 1481.8 25691.1 26749.9 27388.5 27695.6 28033.3 49132.3 49561.3 49578.5 49604.3 49745.8 212049.0 214739.0 215949.0 216698.0 216953.0 33270.3 35000.6 35025.3 35080.6 35247.6 42051.0 42573.6 42585.8 42686.0 42732.4 31537.4 31682.8 32127.5 32373.8 32541.3 31153.5 31163.7 31296.0 31424.5 31620.4 35440.3 35450.3 35796.0 35839.5 35917.3 30401.0 30757.5 30947.0 30958.5 31354.5 22919.2 23166.5 23185.2 23392.4 23979.3 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | [#2] jquery.html Cycles:180 Average:37295.16 Median:23136.09 stddev:93876.01 (405.8%) stddev-sans-first:94098.81 16:49:08 INFO - PID 21619 | Values: 547.4 552.8 557.9 561.4 565.1 21742.9 22574.2 22600.2 22669.2 22691.3 26006.9 26631.5 26708.6 26733.6 26748.6 6504.9 6622.2 6637.2 6643.3 6645.1 5983.2 6517.3 6588.5 6589.3 6660.0 6579.7 6588.6 6597.5 6599.0 6600.0 6595.9 6600.5 6602.5 6603.4 6606.4 6124.5 6126.1 6128.7 6128.9 6134.4 28711.0 29321.4 29825.3 29900.7 29927.6 2103.3 2141.3 2142.0 2142.0 2142.6 18940.6 19659.8 19670.1 19675.4 19684.0 2330.4 2356.6 2393.7 2424.0 2440.0 137788.3 140620.0 140708.3 140790.0 140792.0 13940.3 14810.0 14899.9 15173.2 15342.7 23116.9 23155.3 23199.0 23231.4 23248.0 10023.4 10434.8 10443.0 10494.0 10522.8 8628.5 9814.1 9858.9 9876.8 9997.1 8076.7 8932.2 9165.3 9245.5 9263.1 2531.2 2570.4 2578.7 2677.1 2686.0 956.3 973.0 981.6 988.5 1006.6 19794.0 20253.5 20498.5 23809.9 25123.0 1025.5 1051.5 1064.7 1071.7 1076.7 11498.9 11719.5 11726.6 11770.6 11811.6 554537.0 554936.0 555598.0 570378.0 572723.0 115976.0 117268.0 117324.4 120611.4 124063.0 1243.5 1243.9 1273.9 1275.2 1285.0 18809.2 18889.4 19020.8 19025.6 19284.3 58984.7 59688.0 59996.7 60173.0 61874.2 73836.6 74186.3 74768.6 75359.2 75425.0 12316.1 12957.2 14115.8 14634.6 14963.3 22939.3 23225.7 23644.8 23870.9 23897.6 18691.3 18968.1 19004.6 19062.8 19147.6 16507.2 17083.0 17166.1 17317.7 17543.3 18014.5 19303.1 19338.2 19487.0 19569.9 14422.6 14473.8 14514.7 14517.4 14585.1 18583.3 18634.8 18959.1 18961.1 18980.2 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | [#3] mootools.html Cycles:180 Average:8980.96 Median:2687.46 stddev:6289.71 (234.0%) stddev-sans-first:6281.02 16:49:08 INFO - PID 21619 | Values: 1321.6 1378.8 1393.4 1393.9 1397.4 14242.8 14556.0 14854.7 14866.9 14870.1 15292.9 15546.0 15551.8 15556.4 15570.1 2684.8 2714.2 2715.2 2715.4 2723.8 2437.5 2510.5 2571.8 2572.9 2575.7 2768.6 2788.9 2821.7 2825.6 2826.4 2748.7 2769.8 2772.9 2778.3 2779.7 2690.1 2699.6 2701.9 2703.4 2711.6 14322.9 14615.6 14615.8 14619.6 14633.3 7708.8 7848.8 8219.6 8228.8 8229.0 5327.2 5456.5 5609.3 5611.3 5618.8 1926.4 1945.4 1962.9 1964.5 1979.5 24821.0 25089.1 25110.6 25146.1 25190.6 6527.8 6693.4 6694.3 6853.8 6873.2 16157.8 16180.8 16210.6 16230.5 16247.7 7694.5 7845.5 7873.4 7874.0 7876.4 7145.8 7250.8 7338.8 7387.0 7403.7 5125.2 5293.2 5335.4 5348.7 5362.1 1542.4 1542.9 1552.1 1559.3 1574.0 884.9 908.9 909.3 920.2 922.1 7437.2 7463.3 7519.6 7545.7 7559.2 706.9 722.7 978.7 997.8 1001.5 4745.7 5158.2 5178.3 5300.8 5378.7 19500.1 19894.4 20150.8 20373.7 20418.5 20328.4 20418.7 20505.8 20528.8 20706.5 10811.4 11134.4 11348.3 11359.5 11520.4 8074.2 8231.8 8322.0 8364.3 8450.7 17386.7 17866.8 18069.1 18156.9 18335.3 17766.8 17842.6 17969.5 18145.4 18388.6 9989.5 10210.2 10218.9 10227.6 10231.0 12936.5 13051.8 13100.5 13113.5 13208.7 10510.7 10706.3 10723.7 10749.4 10931.9 10556.5 10619.2 10749.1 10753.2 10835.8 8849.1 8941.8 8943.5 8964.4 8970.4 7001.6 7150.4 7255.1 7480.6 7516.9 6685.2 6725.5 6736.7 6741.4 6776.9 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | [#4] prototype.html Cycles:180 Average:5572.79 Median:6265.08 stddev:1581.38 (25.2%) stddev-sans-first:1539.18 16:49:08 INFO - PID 21619 | Values: 493.8 496.6 503.0 524.1 525.2 6322.2 6361.0 6436.8 6440.0 6500.0 6223.9 6268.3 6299.7 6357.8 6388.0 4922.3 4965.3 5134.4 5180.7 5268.1 4958.3 5014.5 5051.7 5055.1 5055.1 4924.3 4935.0 4953.3 4965.1 5002.2 4903.5 4932.6 4979.6 4982.2 4997.9 4457.9 4458.8 4494.6 4502.9 4511.6 6131.8 6203.8 6243.5 6261.8 6270.1 5696.6 5705.1 5707.6 5724.8 5729.8 6600.4 6613.9 6618.7 6686.9 6701.0 4793.7 4799.5 4847.8 4870.9 4890.6 6707.6 6777.4 6796.8 6886.8 7020.6 4950.9 4960.8 4964.6 4971.6 4985.1 4988.6 5026.3 5047.0 5079.1 5115.7 5600.9 5643.6 5656.9 5658.3 5682.7 6575.4 6650.8 6652.4 6660.5 6685.8 1754.2 1770.6 1779.8 1782.3 1785.1 1152.2 1193.8 1203.8 1206.9 1207.7 5832.0 5863.6 5882.4 5963.4 5964.3 6702.2 6819.2 6834.6 6862.5 6873.8 5880.9 5882.5 5899.5 5903.0 5939.8 3561.9 3584.3 3604.1 3613.8 3664.9 6573.0 6602.4 6606.1 6646.9 6701.7 7024.8 7025.8 7056.2 7064.2 7141.9 6117.4 6674.4 6686.3 6743.3 6753.8 6737.8 6763.7 6763.9 6872.6 6883.4 6697.4 6710.9 6787.4 6802.3 6882.4 6551.2 6564.7 6655.0 6712.9 6714.6 4848.5 5003.2 5018.3 5047.8 5057.4 6572.9 6636.3 6735.9 6882.0 6922.0 6394.4 6499.4 6609.6 6639.3 6652.7 6547.4 6566.1 6593.6 6607.5 6665.8 6494.7 6532.9 6539.5 6542.4 6599.5 6662.3 6665.0 6731.8 6732.0 6779.5 6357.3 6388.7 6483.9 6488.3 6525.2 16:49:08 INFO - PID 21619 | 16:49:08 INFO - PID 21619 | [#5] yui.html Cycles:180 Average:24297.12 Median:21264.76 stddev:33234.73 (156.3%) stddev-sans-first:33281.41 16:49:08 INFO - PID 21619 | Values: 871.4 882.1 900.5 900.6 905.2 16589.9 17489.2 17545.1 17566.6 21189.3 18837.9 19012.1 19642.5 19768.2 19783.8 919.8 946.9 947.4 949.9 949.9 962.2 986.5 998.0 1001.0 1002.2 1068.2 1069.2 1073.6 1073.8 1074.5 1047.3 1047.7 1048.3 1049.3 1050.8 775.8 779.2 782.9 784.2 784.2 18522.3 18783.8 19978.5 20104.4 20112.6 21077.6 21364.0 21528.9 21533.5 21913.5 16837.2 17128.1 17232.9 17350.2 17369.7 3698.7 3705.5 3708.3 3716.1 3724.3 130332.3 130646.0 130791.0 134794.0 135298.3 16178.9 16551.3 16782.3 16791.6 17077.3 30066.7 30192.5 30200.0 31113.8 31363.5 8885.7 8961.3 8975.9 8982.7 9002.7 4409.9 4533.1 4615.3 4633.9 4647.6 5115.8 5240.1 5243.8 5260.3 5266.2 974.8 989.2 993.3 993.5 995.5 629.6 633.8 641.8 646.1 658.2 11600.6 11729.2 11951.9 11963.7 12114.7 635.3 638.7 646.5 654.7 671.0 5644.2 5996.9 6566.0 6576.6 6589.7 110230.0 114350.4 114416.4 115191.0 118521.0 94289.5 94800.5 95056.5 95750.5 97795.0 81990.0 84261.6 90371.0 90879.5 96427.0 12030.5 12776.4 12899.2 13004.0 13014.9 38798.6 40065.8 40101.8 42050.6 42276.4 73615.3 74203.6 75971.0 76477.6 76961.2 18193.3 18369.1 18398.8 18415.3 18458.9 19840.2 20833.9 21154.2 21340.3 21451.8 18235.9 18839.8 18967.7 19328.2 19586.0 17580.9 18077.6 18336.4 18363.4 18522.7 17940.2 18083.7 18143.7 18255.4 18549.6 12433.7 12795.6 13462.5 14083.0 14260.6 14340.9 14913.8 15131.2 15300.0 15379.2 16:49:08 INFO - PID 21619 | -------- Summary: end -------- 16:49:08 INFO - PID 21619 | 16:49:08 INFO - TEST-INFO | 21619: exit 0 16:49:08 INFO - TEST-OK | dromaeo_css | took 1110587ms 16:49:08 INFO - TEST-START | kraken 16:49:08 INFO - Initialising browser for kraken test... 16:49:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46991/getInfo.html -profile /tmp/tmpmgSDhe/profile 16:49:08 INFO - TEST-INFO | started process 21990 (/builds/slave/test/build/application/firefox/firefox http://localhost:46991/getInfo.html) 16:49:14 INFO - TEST-INFO | 21990: exit 0 16:49:14 INFO - Browser initialized. 16:49:14 INFO - Running cycle 1/1 for kraken test... 16:49:14 INFO - Using env: {'DISPLAY': ':0', 16:49:14 INFO - 'HOME': '/home/cltbld', 16:49:14 INFO - 'JSGC_DISABLE_POISONING': '1', 16:49:14 INFO - 'LANG': 'en_US.UTF-8', 16:49:14 INFO - 'LANGUAGE': 'en_US:en', 16:49:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:49:14 INFO - 'LOGNAME': 'cltbld', 16:49:14 INFO - 'MAIL': '/var/mail/cltbld', 16:49:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:49:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:49:14 INFO - 'MOZ_CRASHREPORTER': '1', 16:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:49:14 INFO - 'MOZ_NO_REMOTE': '1', 16:49:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:49:14 INFO - 'NO_EM_RESTART': '1', 16:49:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:14 INFO - 'PWD': '/builds/slave/test', 16:49:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:49:14 INFO - 'RUST_BACKTRACE': 'full', 16:49:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:49:14 INFO - 'SHELL': '/bin/bash', 16:49:14 INFO - 'SHLVL': '1', 16:49:14 INFO - 'STYLO_FORCE_ENABLED': '1', 16:49:14 INFO - 'STYLO_THREADS': '1', 16:49:14 INFO - 'TERM': 'linux', 16:49:14 INFO - 'TMOUT': '86400', 16:49:14 INFO - 'USER': 'cltbld', 16:49:14 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536', 16:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:14 INFO - '_': '/tools/buildbot/bin/python'} 16:49:14 INFO - TEST-INFO | started process 22176 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmgSDhe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:49:15 INFO - PID 22176 | 16:49:15 INFO - PID 22176 | (/builds/slave/test/build/application/firefox/firefox:22227): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:49:15 INFO - PID 22176 | 16:49:25 INFO - PID 22176 | RSS: Main: 170889216 16:49:25 INFO - PID 22176 | 16:49:28 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46991/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:29 INFO - PID 22176 | RSS: Main: 175595520 16:49:29 INFO - PID 22176 | 16:49:31 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46991/tests/kraken/driver.html?testName=audio-dft) 16:49:32 INFO - PID 22176 | RSS: Main: 179724288 16:49:32 INFO - PID 22176 | 16:49:35 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46991/tests/kraken/driver.html?testName=audio-fft) 16:49:36 INFO - PID 22176 | RSS: Main: 172875776 16:49:36 INFO - PID 22176 | 16:49:38 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46991/tests/kraken/driver.html?testName=audio-oscillator) 16:49:38 INFO - PID 22176 | RSS: Main: 173854720 16:49:38 INFO - PID 22176 | 16:49:41 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46991/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:41 INFO - PID 22176 | RSS: Main: 174301184 16:49:41 INFO - PID 22176 | 16:49:44 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46991/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:45 INFO - PID 22176 | RSS: Main: 175206400 16:49:45 INFO - PID 22176 | 16:49:48 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46991/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:49 INFO - PID 22176 | RSS: Main: 174960640 16:49:49 INFO - PID 22176 | 16:49:52 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46991/tests/kraken/driver.html?testName=json-parse-financial) 16:49:53 INFO - PID 22176 | RSS: Main: 175841280 16:49:53 INFO - PID 22176 | 16:49:54 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46991/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:55 INFO - PID 22176 | RSS: Main: 175181824 16:49:55 INFO - PID 22176 | 16:49:56 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:57 INFO - PID 22176 | RSS: Main: 175009792 16:49:57 INFO - PID 22176 | 16:49:59 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:50:00 INFO - PID 22176 | RSS: Main: 172130304 16:50:00 INFO - PID 22176 | 16:50:02 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:50:03 INFO - PID 22176 | RSS: Main: 172388352 16:50:03 INFO - PID 22176 | 16:50:06 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:50:07 INFO - PID 22176 | RSS: Main: 172703744 16:50:07 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | Cycle 1(1): loaded http://localhost:46991/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46991/tests/kraken/driver.html?testName=ai-astar) 16:50:09 INFO - PID 22176 | RSS: Main: 174063616 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | __start_tp_report 16:50:09 INFO - PID 22176 | _x_x_mozilla_page_load 16:50:09 INFO - PID 22176 | _x_x_mozilla_page_load_details 16:50:09 INFO - PID 22176 | |i|pagename|runs| 16:50:09 INFO - PID 22176 | |0;ai-astar;100;106;101;107;106;108;111;98;106;109 16:50:09 INFO - PID 22176 | |1;audio-beat-detection;146;116;152;191;120;154;148;119;173;117 16:50:09 INFO - PID 22176 | |2;audio-dft;163;161;162;162;163;163;162;168;164;170 16:50:09 INFO - PID 22176 | |3;audio-fft;77;76;74;183;79;74;74;73;156;75 16:50:09 INFO - PID 22176 | |4;audio-oscillator;79;79;137;79;79;79;125;79;78;131 16:50:09 INFO - PID 22176 | |5;imaging-gaussian-blur;114;114;117;114;113;116;114;113;114;113 16:50:09 INFO - PID 22176 | |6;imaging-darkroom;148;148;147;148;148;147;147;148;150;155 16:50:09 INFO - PID 22176 | |7;imaging-desaturate;94;94;94;93;93;93;95;95;93;93 16:50:09 INFO - PID 22176 | |8;json-parse-financial;53;53;52;52;52;52;56;52;52;53 16:50:09 INFO - PID 22176 | |9;json-stringify-tinderbox;42;39;38;39;37;37;38;38;37;37 16:50:09 INFO - PID 22176 | |10;stanford-crypto-aes;76;75;74;76;75;76;74;74;75;75 16:50:09 INFO - PID 22176 | |11;stanford-crypto-ccm;126;123;127;132;115;115;119;123;134;166 16:50:09 INFO - PID 22176 | |12;stanford-crypto-pbkdf2;173;179;170;173;172;174;172;168;168;167 16:50:09 INFO - PID 22176 | |13;stanford-crypto-sha256-iterative;65;62;67;66;65;62;65;64;64;65 16:50:09 INFO - PID 22176 | __end_tp_report 16:50:09 INFO - PID 22176 | __start_cc_report 16:50:09 INFO - PID 22176 | _x_x_mozilla_cycle_collect,947 16:50:09 INFO - PID 22176 | __end_cc_report 16:50:09 INFO - PID 22176 | __startTimestamp1504050609307__endTimestamp 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | ------- Summary: start ------- 16:50:09 INFO - PID 22176 | Number of tests: 14 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#0] ai-astar Cycles:10 Average:105.20 Median:107.50 stddev:4.18 (3.9%) stddev-sans-first:3.99 16:50:09 INFO - PID 22176 | Values: 100.0 106.0 101.0 107.0 106.0 108.0 111.0 98.0 106.0 109.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:150.00 stddev:25.70 (17.1%) stddev-sans-first:27.25 16:50:09 INFO - PID 22176 | Values: 146.0 116.0 152.0 191.0 120.0 154.0 148.0 119.0 173.0 117.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:2.90 (1.8%) stddev-sans-first:3.06 16:50:09 INFO - PID 22176 | Values: 163.0 161.0 162.0 162.0 163.0 163.0 162.0 168.0 164.0 170.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#3] audio-fft Cycles:10 Average:94.10 Median:74.50 stddev:40.28 (54.1%) stddev-sans-first:42.25 16:50:09 INFO - PID 22176 | Values: 77.0 76.0 74.0 183.0 79.0 74.0 74.0 73.0 156.0 75.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:79.00 stddev:25.35 (32.1%) stddev-sans-first:26.26 16:50:09 INFO - PID 22176 | Values: 79.0 79.0 137.0 79.0 79.0 79.0 125.0 79.0 78.0 131.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.32 (1.2%) stddev-sans-first:1.39 16:50:09 INFO - PID 22176 | Values: 114.0 114.0 117.0 114.0 113.0 116.0 114.0 113.0 114.0 113.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:148.00 stddev:2.41 (1.6%) stddev-sans-first:2.55 16:50:09 INFO - PID 22176 | Values: 148.0 148.0 147.0 148.0 148.0 147.0 147.0 148.0 150.0 155.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 16:50:09 INFO - PID 22176 | Values: 94.0 94.0 94.0 93.0 93.0 93.0 95.0 95.0 93.0 93.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.50 stddev:1.25 (2.4%) stddev-sans-first:1.32 16:50:09 INFO - PID 22176 | Values: 53.0 53.0 52.0 52.0 52.0 52.0 56.0 52.0 52.0 53.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:1.55 (4.1%) stddev-sans-first:0.83 16:50:09 INFO - PID 22176 | Values: 42.0 39.0 38.0 39.0 37.0 37.0 38.0 38.0 37.0 37.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.00 stddev:0.82 (1.1%) stddev-sans-first:0.78 16:50:09 INFO - PID 22176 | Values: 76.0 75.0 74.0 76.0 75.0 76.0 74.0 74.0 75.0 75.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:126.50 stddev:14.79 (11.7%) stddev-sans-first:15.67 16:50:09 INFO - PID 22176 | Values: 126.0 123.0 127.0 132.0 115.0 115.0 119.0 123.0 134.0 166.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.50 stddev:3.57 (2.1%) stddev-sans-first:3.75 16:50:09 INFO - PID 22176 | Values: 173.0 179.0 170.0 173.0 172.0 174.0 172.0 168.0 168.0 167.0 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.58 (2.4%) stddev-sans-first:1.67 16:50:09 INFO - PID 22176 | Values: 65.0 62.0 67.0 66.0 65.0 62.0 65.0 64.0 64.0 65.0 16:50:09 INFO - PID 22176 | -------- Summary: end -------- 16:50:09 INFO - PID 22176 | 16:50:09 INFO - PID 22176 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:50:09 INFO - TEST-INFO | 22176: exit 0 16:50:09 INFO - TEST-OK | kraken | took 61242ms 16:50:09 INFO - SUITE-END | took 1171s 16:50:10 INFO - Completed test suite (00:19:32) 16:50:10 INFO - javascript benchmark 16:50:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8582.712672115684, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.206510437494, 3306.785058971165, 3313.78460400074, 3351.346487434951, 3358.0261724100014, 6242.1885154929905, 6279.874402511953, 6290.433860952513, 6348.016057511081, 6375.936240637604, 6124.958757011308, 6238.220222472191, 6251.2498500180045, 6291.40231677991, 6297.149884765556, 5811.6803575803315, 5817.243758311435, 5837.620554663955, 5840.853978650525, 5902.2622172228475, 5653.575981801361, 5775.393583673716, 5859.736311865955, 5917.497012753921, 5935.88128237435, 5438.782448702067, 5784.392638772911, 5847.532197424196, 5861.589688721791, 5883.382244864313, 5786.247787787561, 5811.360750317462, 5877.647341159502, 5893.764249430018, 5898.793542226044, 5723.427657234285, 5727.570432217601, 5808.506276966463, 5810.1865738796605, 5815.098659707752, 6243.219597550306, 6279.654618995954, 6292.056191571301, 6313.905291420632, 6330.87338253237, 6698.799036028927, 6716.93283067168, 6778.288279730656, 6791.898121528181, 6795.830104247384, 6788.7963361098755, 6871.896921546181, 6886.208086070116, 6900.309969003059, 6907.309269073053, 6303.684815759239, 6307.810765677037, 6378.0, 6384.170057892445, 6397.648129352884, 6937.063496427918, 6940.028396024561, 6953.756618518328, 7023.086998690138, 7065.187503437068, 6689.397954184147, 6694.866102677919, 6698.22970358415, 6698.83252918681, 6735.831604209934, 6755.662216889136, 6770.052192693027, 6807.65961701913, 6831.316868313129, 6835.179778426521, 6697.832554186087, 6719.092922455406, 6723.193216813916, 6800.047993280945, 6808.455323574102, 6505.251896032017, 6716.664166791738, 6724.226713927961, 6802.965985170043, 6808.8978665320055, 5394.3796463406425, 5399.217113518596, 5406.783728650888, 5409.215663728737, 5412.377576578744, 4679.859604211879, 4688.859334219979, 4733.195356789354, 4759.857204283877, 4796.952030479721, 5852.970735146382, 5874.118882167709, 5877.059670452707, 5952.4047595241, 5955.67243801595, 6838.418734407532, 6838.589684618939, 6860.862782744417, 6878.965605172042, 6887.104676392037, 5891.793787217427, 5912.438318359749, 5947.37552556984, 5980.37206093354, 6008.939910600925, 5715.114157305624, 5780.104083867007, 5831.737571809279, 5905.793297234576, 5913.526917846562, 6507.023946407982, 6619.271880093186, 6649.534532582769, 6666.566673166229, 6670.199576050808, 6766.951122576009, 6769.93230067693, 6850.828729281808, 6870.690818913261, 6871.4502839773695, 6535.44448721864, 6539.0, 6615.6030638161865, 6628.237752658506, 6641.738069766922, 6560.212774466903, 6570.0, 6627.469802415699, 6649.102371179829, 6723.596584204964, 6698.5980841149685, 6701.061851340914, 6781.864362712619, 6783.287754785873, 6783.423409010289, 6456.451201647913, 6469.45009674183, 6548.0, 6555.704993275219, 6565.671716414065, 6475.0, 6477.643729594729, 6644.335566443317, 6653.0, 6658.700358483977, 6528.37980391862, 6530.869382612416, 6600.339966003553, 6619.3049729779605, 6621.33786621334, 6583.868322633616, 6603.306652801579, 6609.570377925419, 6660.666966651745, 6664.600123992576, 6394.072859435263, 6447.645379503984, 6471.288158302489, 6474.417302442803, 6475.158229430144], "value": 6191.03722334501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.128037359838, 860.3203469259284, 880.3529405886673, 881.8104107616861, 1122.0798944865214, 29392.41215175698, 29864.0, 37653.81173094134, 38909.0, 38929.610703892955, 40086.599134008655, 40299.19401611966, 40466.19067618646, 40517.594824051754, 40568.17443215055, 7089.89365159523, 7585.962070189655, 7700.4224683148705, 7704.036995375578, 7705.036870391201, 7507.0, 7617.428692848031, 7621.428392870529, 7622.2758837910305, 7623.085229772435, 7592.620368981557, 7658.655360508763, 7666.846663066735, 7670.654820533062, 7678.616069196546, 7636.618169091579, 7637.809054773619, 7643.42674299427, 7676.7313144040245, 7681.615919204045, 7526.73656422028, 7571.091469023752, 7573.697052117908, 7577.886331705029, 7578.317951384347, 35642.0, 35665.82167089174, 35785.642143578494, 35880.64119358799, 35919.28161436783, 3511.631278715749, 3524.259905419865, 3528.7177025838128, 3551.3074950384685, 3552.378333791602, 29936.0, 30107.849460752775, 30265.546016809767, 30432.391352173054, 30475.847620761975, 5110.233464980282, 5133.30700355451, 5217.269582258449, 5221.086309895791, 5236.24074509194, 183927.0, 184160.0, 184860.0, 185060.0, 185335.0, 45545.316820247725, 46000.76999615048, 46052.3092153618, 46215.075698485845, 46244.07511849811, 46605.76697116493, 46968.295475567895, 47059.0, 47130.5286947133, 47185.05629887383, 13804.654883627989, 15650.0, 15875.52373428799, 15893.125878076586, 15930.123843188505, 11646.24299420535, 13058.34708264583, 13096.14875033139, 13307.800382994263, 13311.73376532464, 13829.446822127002, 13970.580882573538, 14050.508232212029, 14086.859131408759, 14180.574582762534, 5650.74571644277, 5678.5741069419955, 5678.772849086073, 5810.50610698087, 5890.558208134407, 1009.0414106598761, 1016.8779746430475, 1019.2712210769188, 1034.177828626244, 1035.9741006474749, 22447.21434749776, 22536.0, 22636.094556217897, 22716.637001779945, 23036.76963230346, 1112.3048094940661, 1118.2451845004753, 1128.6952522818792, 1133.9262947908524, 1134.6766171641043, 12214.938925305316, 15338.0, 15430.922845385701, 15598.010208409132, 16121.274542645375, 234433.0, 235708.0, 236216.0, 237014.0, 237431.0, 53283.73358133184, 53310.46689533132, 53483.732581336844, 53705.462945370826, 53710.73144634252, 1445.5663301009656, 1460.160407765518, 1478.778183272496, 1481.799957005812, 1481.8444063373406, 25691.100811472257, 26749.866250668623, 27388.45223095487, 27695.58456623112, 28033.299167520974, 49132.26301605407, 49561.25658115203, 49578.50421495883, 49604.25593616026, 49745.75127124341, 212049.0, 214739.0, 215949.0, 216698.0, 216953.0, 33270.334593308486, 35000.64999350076, 35025.299494009465, 35080.64919350876, 35247.647523524436, 42051.0, 42573.5742642582, 42585.78707106445, 42686.0, 42732.35901461419, 31537.36925261436, 31682.841585792845, 32127.518087228244, 32373.838130809196, 32541.349173015933, 31153.532697010018, 31163.688363116078, 31296.0, 31424.52863207008, 31620.36759264848, 35440.29119417649, 35450.29099417946, 35796.0, 35839.46240806442, 35917.28165436624, 30401.0, 30757.53863692091, 30947.0, 30958.53562196614, 31354.52968205433, 22919.197828075936, 23166.536669266858, 23185.188518401774, 23392.415189619715, 23979.28062158138], "value": 17246.810256915982, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.4087984976226, 552.8396764938167, 557.9469950354714, 561.4469747298909, 565.1043096691743, 21742.89128554357, 22574.20990265341, 22600.20899268526, 22669.206577769783, 22691.319260422173, 26006.869965650192, 26631.467370652575, 26708.59937100945, 26733.598996015025, 26748.59877101845, 6504.869902601945, 6622.238442579117, 6637.1703537057865, 6643.269240383554, 6645.102911107011, 5983.1623572699855, 6517.315681853408, 6588.4729221662155, 6589.275179730226, 6660.034295027218, 6579.703913323888, 6588.571742836724, 6597.538172327939, 6599.043138744879, 6600.042993765901, 6595.901061484057, 6600.537962342638, 6602.471802255808, 6603.438707709851, 6606.372394622527, 6124.479419249369, 6126.050462178369, 6128.662923539204, 6128.938710612926, 6134.447899689049, 28711.0, 29321.41357172866, 29825.25436864074, 29900.70099299001, 29927.551086733718, 2103.347962131744, 2141.2719675310314, 2141.967870481944, 2142.0360837623134, 2142.6143294207036, 18940.621187576307, 19659.80340196594, 19670.114844831875, 19675.40973770789, 19684.01579920998, 2330.3940975346363, 2356.646503024543, 2393.676853624756, 2423.9636405453934, 2440.0, 137788.31105844508, 140620.0, 140708.29645851807, 140790.0, 140792.0, 13940.30298485092, 14810.0, 14899.851001489846, 15173.165475898712, 15342.693146137239, 23116.884415577813, 23155.3053408398, 23199.0, 23231.419214519436, 23248.0, 10023.448710321003, 10434.843477347846, 10443.0, 10494.0, 10522.789544209072, 8628.525431101225, 9814.116729494379, 9858.901410985942, 9876.802463950824, 9997.100260976547, 8076.71731489395, 8932.240759535513, 9165.266778657691, 9245.474496707968, 9263.073692630818, 2531.152064058524, 2570.3935040599627, 2578.742125787444, 2677.129932771818, 2685.9731402686116, 956.3114557518714, 973.0464145137804, 981.6417007792104, 988.5057471264367, 1006.6325791086347, 19794.0, 20253.493662658257, 20498.487537811674, 23809.904312910585, 25123.0, 1025.5436330832856, 1051.5215576912487, 1064.669952314785, 1071.694567048403, 1076.6769969009265, 11498.94250528742, 11719.472623731783, 11726.589569365031, 11770.588029419101, 11811.645650630495, 554537.0, 554936.0, 555598.0, 570378.0, 572723.0, 115976.0, 117268.0, 117324.41337793256, 120611.39694301647, 124063.0, 1243.452880732493, 1243.875612438749, 1273.9044571656977, 1275.196626125535, 1285.029802499126, 18809.24763009519, 18889.433317000512, 19020.809791901906, 19025.619487610446, 19284.325048623232, 58984.70507647434, 59688.0, 59996.700016499635, 60173.0, 61874.21566029629, 73836.63081684773, 74186.25813741793, 74768.62615686886, 75359.24640753522, 75425.0, 12316.137870349348, 12957.22256664603, 14115.788263175855, 14634.634134146732, 14963.326650300545, 22939.311820645406, 23225.651615226125, 23644.763552364257, 23870.880645596662, 23897.641535376635, 18691.345802896834, 18968.146433410806, 19004.619907601493, 19062.809371906103, 19147.61704765925, 16507.174641267648, 17083.0, 17166.141692915553, 17317.74023389625, 17543.298268069644, 18014.459566213034, 19303.131359089155, 19338.22647094157, 19487.02564871779, 19569.902150489157, 14422.567322980327, 14473.78289325682, 14514.709705805613, 14517.419303227753, 14585.124892506483, 18583.25666973306, 18634.813651863307, 18959.14683839203, 18961.14674839608, 18980.240790368232], "value": 12212.201795801835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.6497628128545, 1378.8207533020711, 1393.3799459240643, 1393.9163650180988, 1397.3502321420538, 14242.786358204636, 14556.0, 14854.702905941887, 14866.925665371686, 14870.107793532396, 15292.923535382308, 15546.0, 15551.844481555181, 15556.377744890218, 15570.065796052244, 2684.7717943974785, 2714.24001279642, 2715.158300926709, 2715.429759750445, 2723.8093333466663, 2437.549053425122, 2510.535550923085, 2571.832830865997, 2572.9228123156336, 2575.665163528748, 2768.5570308750603, 2788.8745006474755, 2821.675507316664, 2825.6326677531893, 2826.4488424757183, 2748.738869807365, 2769.7645700115413, 2772.8613569321556, 2778.333200031988, 2779.6664400271893, 2690.1391554702504, 2699.5545734953707, 2701.9189424317105, 2703.3917368592106, 2711.64748582685, 14322.928385358007, 14615.63460913475, 14615.78076328856, 14619.63450913725, 14633.268336583234, 7708.768736937901, 7848.7645370638975, 8219.589020548949, 8228.83542329156, 8228.958855205745, 5327.227552004976, 5456.48163424474, 5609.27079479665, 5611.270534830446, 5618.831435056954, 1926.4317026477222, 1945.3774792066545, 1962.8920409377479, 1964.5481539245911, 1979.4556496963307, 24821.0, 25089.12188073427, 25110.623340649905, 25146.11988580409, 25190.622140667903, 6527.771527996498, 6693.397594216446, 6694.25151470369, 6853.7943861684225, 6873.175218973755, 16157.757633635505, 16180.838191617933, 16210.594735131715, 16230.51308460748, 16247.67504649924, 7694.499857509356, 7845.529268243924, 7873.370130389555, 7873.960630196812, 7876.4092693047105, 7145.82135446618, 7250.782476525713, 7338.816529586803, 7387.039684840936, 7403.740869069665, 5125.1799712045895, 5293.179557168645, 5335.439778823245, 5348.705821179872, 5362.061639213122, 1542.3522120709329, 1542.8611424971807, 1552.1463195242573, 1559.3216950626513, 1573.9842601574067, 884.920357167845, 908.8909330880336, 909.3089252168268, 920.2132176988712, 922.1378011559214, 7437.219091995371, 7463.253674632493, 7519.586422746802, 7545.698172073166, 7559.244075592506, 706.8904319830434, 722.7073035420663, 978.7161723100363, 997.81540415022, 1001.5442973447064, 4745.715257084586, 5158.226266060046, 5178.300929374562, 5300.787968481218, 5378.650387724785, 19500.122494487783, 19894.403167904988, 20150.798492015187, 20373.694394584094, 20418.48953776138, 20328.390148295573, 20418.69371959422, 20505.79494205069, 20528.794712052986, 20706.48233794167, 10811.35131892089, 11134.387608681438, 11348.319100853974, 11359.545618175345, 11520.423978801027, 8074.232947869943, 8231.794205144919, 8322.0, 8364.289035432057, 8450.732390141528, 17386.652266954843, 17866.821331787036, 18069.096545172426, 18156.909215454365, 18335.26658933681, 17766.822331776515, 17842.55393615118, 17969.460916172535, 18145.364912228008, 18388.632227355647, 9989.500524973868, 10210.234232432449, 10218.897811022092, 10227.590896364061, 10231.0, 12936.547220847226, 13051.804222936473, 13100.475980961037, 13113.475460981836, 13208.735825283633, 10510.737231569272, 10706.250562460553, 10723.731906702395, 10749.355038697704, 10931.890681093088, 10556.472176390997, 10619.15046796239, 10749.140068794633, 10753.193510486588, 10835.837462437912, 8849.115088491357, 8941.821163576822, 8943.463392196489, 8964.417312874642, 8970.37207395476, 7001.649917504206, 7150.356467917913, 7255.056842610426, 7480.588567628833, 7516.887246691414, 6685.164354455693, 6725.49558783103, 6736.6968486417245, 6741.359570840762, 6776.932230677828], "value": 6505.6768101391835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.8419705694178, 496.5655051829649, 502.9949700502995, 524.1220954900541, 525.1571228178773, 6322.209723784527, 6361.0, 6436.77471288505, 6440.0, 6499.967500162505, 6223.875522489548, 6268.341824108471, 6299.716512756925, 6357.823802596523, 6388.029566251692, 4922.286268491067, 4965.329680493123, 5134.383873935114, 5180.715060671661, 5268.130758424855, 4958.305837182797, 5014.52362025607, 5051.696898186102, 5055.090083784918, 5055.115354812911, 4924.28597853311, 4934.97532512337, 4953.257011448293, 4965.088440950224, 5002.199648056312, 4903.509649035103, 4932.5807306379, 4979.576735977446, 4982.202847544376, 4997.900041999176, 4457.910841783146, 4458.77706114693, 4494.640428765723, 4502.864914052584, 4511.639068874482, 6131.754729810802, 6203.844903877394, 6243.5005199584375, 6261.812145635638, 6270.09083682868, 5696.629719068247, 5705.115707065412, 5707.6290041147195, 5724.82641058582, 5729.828105156852, 6600.405963463263, 6613.908705063659, 6618.7021584028835, 6686.866262674768, 6700.966495167493, 4793.664443488957, 4799.496052914428, 4847.836519235393, 4870.902581948377, 4890.608751299887, 6707.564008339442, 6777.423918966943, 6796.830079248059, 6886.793396198122, 7020.613866237355, 4950.876228094255, 4960.826371077067, 4964.5992495140945, 4971.602271818246, 4985.102681517362, 4988.6009119270375, 5026.29631851541, 5047.0, 5079.111155547838, 5115.7442127893455, 5600.943990560124, 5643.604947653625, 5656.8868622627315, 5658.34928983164, 5682.715864206856, 6575.375339342755, 6650.833729156713, 6652.401283884474, 6660.467162626978, 6685.832854178684, 1754.1580041580114, 1770.6104656975444, 1779.7953235378097, 1782.269269599458, 1785.1252886085983, 1152.2164927849144, 1193.8030225012776, 1203.789336866045, 1206.8853458921565, 1207.728261141236, 5831.970840145772, 5863.560232982543, 5882.3529411764675, 5963.403659634002, 5964.343922168558, 6702.246741824594, 6819.24988251292, 6834.624095674686, 6862.4853136015, 6873.828154296182, 5880.882382352329, 5882.499937526534, 5899.469047785634, 5903.0, 5939.752651943054, 3561.9287614248087, 3584.336897673939, 3604.098975256186, 3613.837377318027, 3664.871729489455, 6572.967135164389, 6602.39205513829, 6606.141201643755, 6646.900296495556, 6701.731930722814, 7024.824379390556, 7025.754098606524, 7056.223815380304, 7064.1876184238445, 7141.857162856713, 6117.449429551361, 6674.399304062657, 6686.3313668632745, 6743.258241593421, 6753.763618273337, 6737.764178253738, 6763.695633696397, 6763.898541521783, 6872.553284036521, 6883.449324053963, 6697.430718388991, 6710.932890671031, 6787.389134977876, 6802.285759995327, 6882.449404047564, 6551.21385433751, 6564.737410503717, 6655.0, 6712.865742685021, 6714.563553369016, 4848.466668666373, 5003.1744762115295, 5018.272350509229, 5047.823326183566, 5057.393112826484, 6572.901406479003, 6636.336366363325, 6735.898961515679, 6881.965590172017, 6922.0, 6394.424501794861, 6499.425316076234, 6609.603423794588, 6639.269680335063, 6652.7006284716335, 6547.410733034049, 6566.143727478371, 6593.637349945799, 6607.504437167136, 6665.766698165541, 6494.7077381517, 6532.902006469812, 6539.542232043711, 6542.378474044958, 6599.472042236705, 6662.333766623299, 6665.0, 6731.798046058626, 6731.966340168267, 6779.525433219627, 6357.300696923242, 6388.68056597159, 6483.902741458787, 6488.318726533646, 6525.184351956005], "value": 5102.872746217695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [871.4379225399616, 882.0914458108151, 900.5002223765807, 900.5677274908041, 905.162724479856, 16589.917050414748, 17489.212985415656, 17545.122743862805, 17566.560835979108, 21189.258375956844, 18837.905810470962, 19012.14445349959, 19642.508937276576, 19768.20927162912, 19783.802161978376, 919.8114386550775, 946.8627049077879, 947.4362754161263, 949.8860136783595, 949.9335046546745, 962.2494454325603, 986.4968865878418, 998.001998001998, 1000.9949950250239, 1002.1531805624236, 1068.2041878800267, 1069.1553672598639, 1073.618865302818, 1073.7798751255973, 1074.462768615692, 1047.314009323894, 1047.6909311752975, 1048.3238311289215, 1049.2707568240075, 1050.8318669012922, 775.7672698190577, 779.1857508903153, 782.9334506566948, 784.1844481738984, 784.2236186175645, 18522.259109635597, 18783.812161878344, 19978.500537486678, 20104.391648668072, 20112.597748045104, 21077.578448430944, 21364.0, 21528.89235553828, 21533.46166345854, 21913.452163695874, 16837.158142092845, 17128.14359282031, 17232.913835430867, 17350.219240134225, 17369.652606947915, 3698.685611723007, 3705.5182826232417, 3708.332500149972, 3716.145286584074, 3724.3296206682785, 130332.3483382577, 130646.0, 130791.0, 134794.0, 135298.3235083828, 16178.919105404397, 16551.25519351632, 16782.32870685159, 16791.58021049459, 17077.31690732357, 30066.69933300639, 30192.54711179334, 30200.0, 31113.844430777703, 31363.529547056813, 8885.733427997171, 8961.283097352327, 8975.865362019575, 8982.730518084469, 9002.684906028258, 4409.933850992237, 4533.116042371706, 4615.284630882219, 4633.907321853544, 4647.6281897448125, 5115.820946266937, 5240.0567897778, 5243.764030618631, 5260.316158899319, 5266.21006848968, 974.8440249560036, 989.223459584223, 993.2699465892563, 993.4734590666958, 995.4773743784426, 629.6033498895666, 633.8130251575797, 641.8074577626693, 646.0825627608807, 658.2430205263889, 11600.635815613377, 11729.237599556172, 11951.940240298743, 11963.700907477383, 12114.697132571757, 635.269440143829, 638.6774678787183, 646.524804268862, 654.7381047581006, 670.9597424154566, 5644.209810626516, 5996.940030599725, 6566.0, 6576.638284894281, 6589.703463344161, 110230.0, 114350.42824785823, 114416.42791786154, 115191.0, 118521.0, 94289.5285523568, 94800.52599736956, 95056.52471737735, 95750.52124739332, 97795.0, 81990.0, 84261.57869210615, 90371.0, 90879.54560227157, 96427.0, 12030.518779248732, 12776.425060872469, 12899.226046437243, 13004.0, 13014.934925325313, 38798.6120138795, 40065.799671001456, 40101.799491002355, 42050.57949420466, 42276.36585451159, 73615.26384736084, 74203.62898185475, 75971.0, 76477.61761191158, 76961.23038769541, 18193.272269109086, 18369.081545922916, 18398.816011840245, 18415.26338946427, 18458.907705461388, 19840.20639174417, 20833.89583052075, 21154.153833846474, 21340.253091141734, 21451.78548214498, 18235.908820455814, 18839.811601884354, 18967.715484268025, 19328.226870925006, 19586.0, 17580.945143291443, 18077.638447231246, 18336.358227461973, 18363.35728249505, 18522.722159167894, 17940.19269132866, 18083.728744069114, 18143.727844082085, 18255.361062362754, 18549.629007420044, 12433.689157771129, 12795.552155674506, 13462.528811491551, 14083.0, 14260.572182834532, 14340.928295358874, 14913.776293355391, 15131.167785771888, 15300.0, 15379.23103844781], "value": 9233.106949541587, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 106.0, 101.0, 107.0, 106.0, 108.0, 111.0, 98.0, 106.0, 109.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 152.0, 191.0, 120.0, 154.0, 148.0, 119.0, 173.0, 117.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 162.0, 163.0, 163.0, 162.0, 168.0, 164.0, 170.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 74.0, 183.0, 79.0, 74.0, 74.0, 73.0, 156.0, 75.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 137.0, 79.0, 79.0, 79.0, 125.0, 79.0, 78.0, 131.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 117.0, 114.0, 113.0, 116.0, 114.0, 113.0, 114.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 148.0, 147.0, 147.0, 148.0, 150.0, 155.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 93.0, 95.0, 95.0, 93.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 52.0, 52.0, 52.0, 56.0, 52.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 39.0, 38.0, 39.0, 37.0, 37.0, 38.0, 38.0, 37.0, 37.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 76.0, 75.0, 76.0, 74.0, 74.0, 75.0, 75.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 123.0, 127.0, 132.0, 115.0, 115.0, 119.0, 123.0, 134.0, 166.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 179.0, 170.0, 173.0, 172.0, 174.0, 172.0, 168.0, 168.0, 167.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 67.0, 66.0, 65.0, 62.0, 65.0, 64.0, 64.0, 65.0], "value": 64.5, "unit": "score"}]}]} 16:50:10 INFO - Return code: 0 16:50:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:50:10 INFO - # TBPL SUCCESS # 16:50:10 INFO - Running post-action listener: _package_coverage_data 16:50:10 INFO - Running post-action listener: _resource_record_post_action 16:50:10 INFO - [mozharness: 2017-08-29 23:50:10.583788Z] Finished run-tests step (success) 16:50:10 INFO - Running post-run listener: _resource_record_post_run 16:50:10 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 77824; Write bytes: 402382848; Read time: 24; Write time: 241904 16:50:10 INFO - TinderboxPrint: CPU usage
13.1% 16:50:10 INFO - TinderboxPrint: I/O read bytes / time
77,824 / 24 16:50:10 INFO - TinderboxPrint: I/O write bytes / time
402,382,848 / 241,904 16:50:10 INFO - TinderboxPrint: CPU idle
8,239.6 (86.9%) 16:50:10 INFO - TinderboxPrint: CPU user
1,192.3 (12.6%) 16:50:10 INFO - TinderboxPrint: Swap in / out
0 / 0 16:50:10 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115073024; Read time: 0; Write time: 84112 16:50:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:50:10 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 77824; Write bytes: 287309824; Read time: 24; Write time: 157792 16:50:11 INFO - Running post-run listener: _upload_blobber_files 16:50:11 INFO - Blob upload gear active. 16:50:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:50: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 16:50: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'] 16:50: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 16:50:11 INFO - (blobuploader) - INFO - Open directory for files ... 16:50:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:50:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:50:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:14 INFO - (blobuploader) - INFO - Done attempting. 16:50:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:50:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:50:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:15 INFO - (blobuploader) - INFO - Done attempting. 16:50:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:50:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:50:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:16 INFO - (blobuploader) - INFO - Done attempting. 16:50:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:50:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:50:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:17 INFO - (blobuploader) - INFO - Done attempting. 16:50:17 INFO - (blobuploader) - INFO - Iteration through files over. 16:50:17 INFO - Return code: 0 16:50:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:50:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:50:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88824275316229e771852124dcf71e665e08e07eaa42491c28304d54cbe95b698e521308eacd08235768013ef74fe0e74c5866de598fae7f09f38ae0628f392", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c24de91256a7cbcbbebb34a703ab917f626bbe1094b4251166b30e56720fea1901951c05c4c729ac2328d7987057cbccd680c645568a473a2c328702612f5bd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed6a171c50d6facd77a57fcf611c3256a6d380aab015ac691f9bac9396bd4b7d564ec59627b697d30d998161683fb2f1b384acf1e43ce5d1631d72f56e761c08", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5daca0072d457fe0ef1d73c666a99852c50bcee63428545210f919c507c377466e1eaeaa237dc2aa5146bf50cefde44072731faeb2539210060dab2431c1cbf7"} 16:50:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:50:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:50:17 INFO - Contents: 16:50:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88824275316229e771852124dcf71e665e08e07eaa42491c28304d54cbe95b698e521308eacd08235768013ef74fe0e74c5866de598fae7f09f38ae0628f392", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c24de91256a7cbcbbebb34a703ab917f626bbe1094b4251166b30e56720fea1901951c05c4c729ac2328d7987057cbccd680c645568a473a2c328702612f5bd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed6a171c50d6facd77a57fcf611c3256a6d380aab015ac691f9bac9396bd4b7d564ec59627b697d30d998161683fb2f1b384acf1e43ce5d1631d72f56e761c08", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5daca0072d457fe0ef1d73c666a99852c50bcee63428545210f919c507c377466e1eaeaa237dc2aa5146bf50cefde44072731faeb2539210060dab2431c1cbf7"} 16:50:17 INFO - Running post-run listener: copy_logs_to_upload_dir 16:50:17 INFO - Copying logs to upload dir... 16:50:17 INFO - mkdir: /builds/slave/test/build/upload/logs 16:50:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.692228 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-08-29 16:50:17.686743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:17.694499) ========= 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=32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88824275316229e771852124dcf71e665e08e07eaa42491c28304d54cbe95b698e521308eacd08235768013ef74fe0e74c5866de598fae7f09f38ae0628f392", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c24de91256a7cbcbbebb34a703ab917f626bbe1094b4251166b30e56720fea1901951c05c4c729ac2328d7987057cbccd680c645568a473a2c328702612f5bd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed6a171c50d6facd77a57fcf611c3256a6d380aab015ac691f9bac9396bd4b7d564ec59627b697d30d998161683fb2f1b384acf1e43ce5d1631d72f56e761c08", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5daca0072d457fe0ef1d73c666a99852c50bcee63428545210f919c507c377466e1eaeaa237dc2aa5146bf50cefde44072731faeb2539210060dab2431c1cbf7"} build_url:https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/OLOX1rRMRjiLLTkG5J30Ew/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e88824275316229e771852124dcf71e665e08e07eaa42491c28304d54cbe95b698e521308eacd08235768013ef74fe0e74c5866de598fae7f09f38ae0628f392", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c24de91256a7cbcbbebb34a703ab917f626bbe1094b4251166b30e56720fea1901951c05c4c729ac2328d7987057cbccd680c645568a473a2c328702612f5bd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed6a171c50d6facd77a57fcf611c3256a6d380aab015ac691f9bac9396bd4b7d564ec59627b697d30d998161683fb2f1b384acf1e43ce5d1631d72f56e761c08", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5daca0072d457fe0ef1d73c666a99852c50bcee63428545210f919c507c377466e1eaeaa237dc2aa5146bf50cefde44072731faeb2539210060dab2431c1cbf7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:17.723657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:17.723973) ========= 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=32c5991c409366f7fec8a718000001d2-1504047246.6700-1726518536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004585 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 16:50:17.805949) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 16:50:17.809269) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 16:50:17.809557) ========= ========= Total master_lag: 0.17 =========