builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-080 starttime: 1496711711.82 results: success (0) revision: 2c6289f56812c30254acfdddabcfec1e149c0336 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:11.818114) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:11.818602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:11.819064) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:11.923319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:11.923605) ========= 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=781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-05 18:15:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.7M=0s 2017-06-05 18:15:12 (37.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147172 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:12.115221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:12.115542) ========= 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=781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014874 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:12.201575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 18:15:12.201934) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2c6289f56812c30254acfdddabcfec1e149c0336 --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 2c6289f56812c30254acfdddabcfec1e149c0336 --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=781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-05 18:15:12,323 truncating revision to first 12 chars 2017-06-05 18:15:12,323 Setting DEBUG logging. 2017-06-05 18:15:12,323 attempt 1/10 2017-06-05 18:15:12,323 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c6289f56812?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 18:15:13,021 unpacking tar archive at: mozilla-central-2c6289f56812/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299203 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 18:15:13.551280) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:13.551643) ========= script_repo_revision: 2c6289f56812c30254acfdddabcfec1e149c0336 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:13.552192) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:13.552587) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:15:13.563250) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2017-06-05 18:15:13.563654) ========= /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=781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:13 INFO - MultiFileLogger online at 20170605 18:15:13 in /builds/slave/test 18:15:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:15:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:13 INFO - {'append_to_log': False, 18:15:13 INFO - 'base_work_dir': '/builds/slave/test', 18:15:13 INFO - 'blob_upload_branch': 'Firefox', 18:15:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:13 INFO - 'branch': 'Firefox', 18:15:13 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:13 INFO - 'config_files': ('talos/linux_config.py',), 18:15:13 INFO - 'default_actions': ('clobber', 18:15:13 INFO - 'read-buildbot-config', 18:15:13 INFO - 'download-and-extract', 18:15:13 INFO - 'populate-webroot', 18:15:13 INFO - 'create-virtualenv', 18:15:13 INFO - 'install', 18:15:13 INFO - 'setup-mitmproxy', 18:15:13 INFO - 'run-tests'), 18:15:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:13 INFO - 'download_minidump_stackwalk': True, 18:15:13 INFO - 'download_symbols': 'ondemand', 18:15:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:15:13 INFO - 'tooltool.py': '/tools/tooltool.py', 18:15:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:15:13 INFO - '/tools/misc-python/virtualenv.py')}, 18:15:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:13 INFO - 'gecko_profile': False, 18:15:13 INFO - 'gecko_profile_interval': 0, 18:15:13 INFO - 'installer_path': 'installer.exe', 18:15:13 INFO - 'log_level': 'info', 18:15:13 INFO - 'log_name': 'talos', 18:15:13 INFO - 'log_to_console': True, 18:15:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:13 INFO - 'opt_config_files': (), 18:15:13 INFO - 'pip_index': False, 18:15:13 INFO - 'suite': 'dromaeojs-e10s', 18:15:13 INFO - 'system_bits': '32', 18:15:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:13 INFO - 'title': 'talos-linux64-ix-080', 18:15:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:13 INFO - 'use_talos_json': True, 18:15:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:13 INFO - 'work_dir': 'build'} 18:15:13 INFO - [mozharness: 2017-06-06 01:15:13.778698Z] Running clobber step. 18:15:13 INFO - Running pre-action listener: _resource_record_pre_action 18:15:13 INFO - Running main action method: clobber 18:15:13 INFO - rmtree: /builds/slave/test/build 18:15:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:14 INFO - Running post-action listener: _resource_record_post_action 18:15:14 INFO - [mozharness: 2017-06-06 01:15:14.802230Z] Finished clobber step (success) 18:15:14 INFO - [mozharness: 2017-06-06 01:15:14.802334Z] Running read-buildbot-config step. 18:15:14 INFO - Running pre-action listener: _resource_record_pre_action 18:15:14 INFO - Running main action method: read_buildbot_config 18:15:14 INFO - Using buildbot properties: 18:15:14 INFO - { 18:15:14 INFO - "project": "", 18:15:14 INFO - "product": "firefox", 18:15:14 INFO - "who": "kwierso@gmail.com", 18:15:14 INFO - "installer_path": "public/build/target.tar.bz2", 18:15:14 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:15:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:15:14 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:15:14 INFO - "stage_platform": "linux64-stylosequential", 18:15:14 INFO - "basedir": "/builds/slave/test", 18:15:14 INFO - "buildnumber": 45, 18:15:14 INFO - "platform": "ubuntu64_hw_styloseq", 18:15:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:15:14 INFO - "slavebuilddir": "test", 18:15:14 INFO - "taskId": "J-jq4w_PTYyQnc67-EfE_g", 18:15:14 INFO - "branch": "mozilla-central", 18:15:14 INFO - "script_repo_revision": "production", 18:15:14 INFO - "revision": "2c6289f56812c30254acfdddabcfec1e149c0336", 18:15:14 INFO - "slavename": "talos-linux64-ix-080", 18:15:14 INFO - "repo_path": "mozilla-central" 18:15:14 INFO - } 18:15:14 INFO - Finding installer, test and symbols from parent task. 18:15:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J-jq4w_PTYyQnc67-EfE_g'}, attempt #1 18:15:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J-jq4w_PTYyQnc67-EfE_g'}, attempt #1 18:15:15 INFO - Task dependencies: BgQ-WGk2R7y7z_N4RN3DOQ 18:15:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ'}, attempt #1 18:15:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2 18:15:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/test_packages.json 18:15:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.crashreporter-symbols.zip 18:15:16 INFO - Running post-action listener: _resource_record_post_action 18:15:16 INFO - [mozharness: 2017-06-06 01:15:16.152797Z] Finished read-buildbot-config step (success) 18:15:16 INFO - [mozharness: 2017-06-06 01:15:16.153016Z] Running download-and-extract step. 18:15:16 INFO - Running pre-action listener: _resource_record_pre_action 18:15:16 INFO - Running main action method: download_and_extract 18:15:16 INFO - mkdir: /builds/slave/test/build/tests 18:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:16 INFO - trying https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.test_packages.json 18:15:16 INFO - Downloading https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:15:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:15:16 INFO - Downloaded 989 bytes. 18:15:16 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:15:16 INFO - Using the following test package requirements: 18:15:16 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:15:16 INFO - u'common': [u'target.common.tests.zip'], 18:15:16 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:15:16 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:15:16 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:15:16 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:15:16 INFO - u'mozbase': [u'target.common.tests.zip'], 18:15:16 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:15:16 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:15:16 INFO - u'web-platform': [u'target.common.tests.zip', 18:15:16 INFO - u'target.web-platform.tests.tar.gz'], 18:15:16 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:15:16 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:15:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.common.tests.zip 18:15:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:15:16 INFO - Fetch https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.common.tests.zip into memory 18:15:22 INFO - Content-Length response header: 65102942 18:15:22 INFO - Bytes received: 65102942 18:15:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:15:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.common.tests.zip 18:15:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:15:33 INFO - Fetch https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.common.tests.zip into memory 18:15:37 INFO - Content-Length response header: 65102942 18:15:37 INFO - Bytes received: 65102942 18:15:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.talos.tests.zip 18:15:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:15:50 INFO - Fetch https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.talos.tests.zip into memory 18:15:50 INFO - Content-Length response header: 14447425 18:15:50 INFO - Bytes received: 14447425 18:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:51 INFO - trying https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2 18:15:51 INFO - Downloading https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:15:54 INFO - Downloaded 60489000 bytes. 18:15:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2 18:15:54 INFO - mkdir: /builds/slave/test/properties 18:15:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:15:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:15:54 INFO - Contents: 18:15:54 INFO - build_url:https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2 18:15:54 INFO - Running post-action listener: _resource_record_post_action 18:15:54 INFO - Running post-action listener: set_extra_try_arguments 18:15:54 INFO - [mozharness: 2017-06-06 01:15:54.749243Z] Finished download-and-extract step (success) 18:15:54 INFO - [mozharness: 2017-06-06 01:15:54.749469Z] Running populate-webroot step. 18:15:54 INFO - Running pre-action listener: _resource_record_pre_action 18:15:54 INFO - Running main action method: populate_webroot 18:15:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:15:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:15:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:15:54 INFO - '--tppagecycles', 18:15:54 INFO - '7'], 18:15:54 INFO - 'tests': ['tsvgm']}}, 18:15:54 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:15:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:15:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:15:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['damp', 'tps']}, 18:15:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'tests': ['damp', 'tps']}, 18:15:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['dromaeo_dom']}, 18:15:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:15:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:15:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:15:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:15:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['a11yr', 18:15:54 INFO - 'ts_paint', 18:15:54 INFO - 'tpaint', 18:15:54 INFO - 'sessionrestore', 18:15:54 INFO - 'sessionrestore_no_auto_restore', 18:15:54 INFO - 'tabpaint']}, 18:15:54 INFO - 'other-e10s': {'tests': ['a11yr', 18:15:54 INFO - 'ts_paint', 18:15:54 INFO - 'tpaint', 18:15:54 INFO - 'sessionrestore', 18:15:54 INFO - 'sessionrestore_no_auto_restore', 18:15:54 INFO - 'tabpaint']}, 18:15:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:15:54 INFO - 'ts_paint', 18:15:54 INFO - 'tpaint', 18:15:54 INFO - 'sessionrestore', 18:15:54 INFO - 'sessionrestore_no_auto_restore', 18:15:54 INFO - 'tabpaint']}, 18:15:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:15:54 INFO - 'ts_paint', 18:15:54 INFO - 'tpaint', 18:15:54 INFO - 'sessionrestore', 18:15:54 INFO - 'sessionrestore_no_auto_restore', 18:15:54 INFO - 'tabpaint']}, 18:15:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['a11yr', 18:15:54 INFO - 'ts_paint', 18:15:54 INFO - 'tpaint', 18:15:54 INFO - 'sessionrestore', 18:15:54 INFO - 'sessionrestore_no_auto_restore', 18:15:54 INFO - 'tabpaint']}, 18:15:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['a11yr', 18:15:54 INFO - 'ts_paint', 18:15:54 INFO - 'tpaint', 18:15:54 INFO - 'sessionrestore', 18:15:54 INFO - 'sessionrestore_no_auto_restore', 18:15:54 INFO - 'tabpaint']}, 18:15:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:15:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:15:54 INFO - 'bloom_basic_ref']}, 18:15:54 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:15:54 INFO - 'talos_options': ['--mitmproxy', 18:15:54 INFO - 'mitmproxy-recording-1.mp', 18:15:54 INFO - '--firstNonBlankPaint'], 18:15:54 INFO - 'tests': ['Quantum_1']}, 18:15:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['tsvgx', 18:15:54 INFO - 'tsvgr_opacity', 18:15:54 INFO - 'tart', 18:15:54 INFO - 'tscrollx', 18:15:54 INFO - 'cart', 18:15:54 INFO - 'tsvg_static']}, 18:15:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:15:54 INFO - 'tsvgr_opacity', 18:15:54 INFO - 'tart', 18:15:54 INFO - 'tscrollx', 18:15:54 INFO - 'cart', 18:15:54 INFO - 'tsvg_static']}, 18:15:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'talos_options': ['--disable-e10s'], 18:15:54 INFO - 'tests': ['tp5o']}, 18:15:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:15:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'talos_options': ['--disable-e10s', 18:15:54 INFO - '--xperf_path', 18:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:54 INFO - 'tests': ['tp5n']}, 18:15:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:15:54 INFO - 'talos_options': ['--xperf_path', 18:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:54 INFO - 'tests': ['tp5n']}}, 18:15:54 INFO - 'talos.zip': {'path': '', 18:15:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:15:54 INFO - Running post-action listener: _resource_record_post_action 18:15:54 INFO - [mozharness: 2017-06-06 01:15:54.766819Z] Finished populate-webroot step (success) 18:15:54 INFO - [mozharness: 2017-06-06 01:15:54.766909Z] Running create-virtualenv step. 18:15:54 INFO - Running pre-action listener: _resource_record_pre_action 18:15:54 INFO - Running main action method: create_virtualenv 18:15:54 INFO - Creating virtualenv /builds/slave/test/build/venv 18:15:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:15:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:15:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:15:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:15:54 INFO - Using real prefix '/usr' 18:15:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:15:55 INFO - Installing distribute.............................................................................................................................................................................................done. 18:15:57 INFO - Installing pip.................done. 18:15:57 INFO - Return code: 0 18:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:15:57 INFO - Reading from file tmpfile_stdout 18:15:57 INFO - Output received: 18:15:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:15:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:15:57 INFO - Using env: {'DISPLAY': ':0', 18:15:57 INFO - 'HOME': '/home/cltbld', 18:15:57 INFO - 'LANG': 'en_US.UTF-8', 18:15:57 INFO - 'LANGUAGE': 'en_US:en', 18:15:57 INFO - 'LOGNAME': 'cltbld', 18:15:57 INFO - 'MAIL': '/var/mail/cltbld', 18:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:57 INFO - 'MOZ_NO_REMOTE': '1', 18:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:57 INFO - 'NO_EM_RESTART': '1', 18:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:57 INFO - 'PWD': '/builds/slave/test', 18:15:57 INFO - 'SHELL': '/bin/bash', 18:15:57 INFO - 'SHLVL': '1', 18:15:57 INFO - 'TERM': 'linux', 18:15:57 INFO - 'TMOUT': '86400', 18:15:57 INFO - 'USER': 'cltbld', 18:15:57 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:57 INFO - '_': '/tools/buildbot/bin/python'} 18:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:15:57 INFO - Cleaning up... 18:15:57 INFO - Return code: 0 18:15:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:15:57 INFO - Using env: {'DISPLAY': ':0', 18:15:57 INFO - 'HOME': '/home/cltbld', 18:15:57 INFO - 'LANG': 'en_US.UTF-8', 18:15:57 INFO - 'LANGUAGE': 'en_US:en', 18:15:57 INFO - 'LOGNAME': 'cltbld', 18:15:57 INFO - 'MAIL': '/var/mail/cltbld', 18:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:57 INFO - 'MOZ_NO_REMOTE': '1', 18:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:57 INFO - 'NO_EM_RESTART': '1', 18:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:57 INFO - 'PWD': '/builds/slave/test', 18:15:57 INFO - 'SHELL': '/bin/bash', 18:15:57 INFO - 'SHLVL': '1', 18:15:57 INFO - 'TERM': 'linux', 18:15:57 INFO - 'TMOUT': '86400', 18:15:57 INFO - 'USER': 'cltbld', 18:15:57 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:57 INFO - '_': '/tools/buildbot/bin/python'} 18:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:57 INFO - Downloading/unpacking psutil>=3.1.1 18:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:15:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:15:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:15:59 INFO - Installing collected packages: psutil 18:15:59 INFO - Running setup.py install for psutil 18:15:59 INFO - building 'psutil._psutil_linux' extension 18:15:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:15:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:15:59 INFO - building 'psutil._psutil_posix' extension 18:15:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:15:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:15:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:15:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:15:59 INFO - Successfully installed psutil 18:15:59 INFO - Cleaning up... 18:15:59 INFO - Return code: 0 18:15:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:59 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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:15:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:15:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:15:59 INFO - Using env: {'DISPLAY': ':0', 18:15:59 INFO - 'HOME': '/home/cltbld', 18:15:59 INFO - 'LANG': 'en_US.UTF-8', 18:15:59 INFO - 'LANGUAGE': 'en_US:en', 18:15:59 INFO - 'LOGNAME': 'cltbld', 18:15:59 INFO - 'MAIL': '/var/mail/cltbld', 18:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:59 INFO - 'MOZ_NO_REMOTE': '1', 18:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:59 INFO - 'NO_EM_RESTART': '1', 18:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:59 INFO - 'PWD': '/builds/slave/test', 18:15:59 INFO - 'SHELL': '/bin/bash', 18:15:59 INFO - 'SHLVL': '1', 18:15:59 INFO - 'TERM': 'linux', 18:15:59 INFO - 'TMOUT': '86400', 18:15:59 INFO - 'USER': 'cltbld', 18:15:59 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:59 INFO - '_': '/tools/buildbot/bin/python'} 18:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:16:01 INFO - Installing collected packages: mozsystemmonitor 18:16:01 INFO - Running setup.py install for mozsystemmonitor 18:16:01 INFO - Successfully installed mozsystemmonitor 18:16:01 INFO - Cleaning up... 18:16:01 INFO - Return code: 0 18:16:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:16:01 INFO - Using env: {'DISPLAY': ':0', 18:16:01 INFO - 'HOME': '/home/cltbld', 18:16:01 INFO - 'LANG': 'en_US.UTF-8', 18:16:01 INFO - 'LANGUAGE': 'en_US:en', 18:16:01 INFO - 'LOGNAME': 'cltbld', 18:16:01 INFO - 'MAIL': '/var/mail/cltbld', 18:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:01 INFO - 'MOZ_NO_REMOTE': '1', 18:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:01 INFO - 'NO_EM_RESTART': '1', 18:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:01 INFO - 'PWD': '/builds/slave/test', 18:16:01 INFO - 'SHELL': '/bin/bash', 18:16:01 INFO - 'SHLVL': '1', 18:16:01 INFO - 'TERM': 'linux', 18:16:01 INFO - 'TMOUT': '86400', 18:16:01 INFO - 'USER': 'cltbld', 18:16:01 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:01 INFO - '_': '/tools/buildbot/bin/python'} 18:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:01 INFO - Downloading/unpacking jsonschema==2.5.1 18:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:16:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:02 INFO - Downloading functools32-3.2.3-2.tar.gz 18:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:16:02 INFO - warning: no files found matching '*.txt' 18:16:02 INFO - no previously-included directories found matching 'build' 18:16:02 INFO - no previously-included directories found matching 'dist' 18:16:02 INFO - no previously-included directories found matching '.git*' 18:16:02 INFO - Installing collected packages: jsonschema, functools32 18:16:02 INFO - Running setup.py install for functools32 18:16:02 INFO - warning: no files found matching '*.txt' 18:16:02 INFO - no previously-included directories found matching 'build' 18:16:02 INFO - no previously-included directories found matching 'dist' 18:16:02 INFO - no previously-included directories found matching '.git*' 18:16:02 INFO - Successfully installed jsonschema functools32 18:16:02 INFO - Cleaning up... 18:16:02 INFO - Return code: 0 18:16:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:02 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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:16:02 INFO - Using env: {'DISPLAY': ':0', 18:16:02 INFO - 'HOME': '/home/cltbld', 18:16:02 INFO - 'LANG': 'en_US.UTF-8', 18:16:02 INFO - 'LANGUAGE': 'en_US:en', 18:16:02 INFO - 'LOGNAME': 'cltbld', 18:16:02 INFO - 'MAIL': '/var/mail/cltbld', 18:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:02 INFO - 'MOZ_NO_REMOTE': '1', 18:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:02 INFO - 'NO_EM_RESTART': '1', 18:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:02 INFO - 'PWD': '/builds/slave/test', 18:16:02 INFO - 'SHELL': '/bin/bash', 18:16:02 INFO - 'SHLVL': '1', 18:16:02 INFO - 'TERM': 'linux', 18:16:02 INFO - 'TMOUT': '86400', 18:16:02 INFO - 'USER': 'cltbld', 18:16:02 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:02 INFO - '_': '/tools/buildbot/bin/python'} 18:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:16:03 INFO - Cleaning up... 18:16:03 INFO - Return code: 0 18:16:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16: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', '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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:16: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 blobuploader==1.2.4 18:16:03 INFO - Using env: {'DISPLAY': ':0', 18:16:03 INFO - 'HOME': '/home/cltbld', 18:16:03 INFO - 'LANG': 'en_US.UTF-8', 18:16:03 INFO - 'LANGUAGE': 'en_US:en', 18:16:03 INFO - 'LOGNAME': 'cltbld', 18:16:03 INFO - 'MAIL': '/var/mail/cltbld', 18:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:03 INFO - 'MOZ_NO_REMOTE': '1', 18:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:03 INFO - 'NO_EM_RESTART': '1', 18:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:03 INFO - 'PWD': '/builds/slave/test', 18:16:03 INFO - 'SHELL': '/bin/bash', 18:16:03 INFO - 'SHLVL': '1', 18:16:03 INFO - 'TERM': 'linux', 18:16:03 INFO - 'TMOUT': '86400', 18:16:03 INFO - 'USER': 'cltbld', 18:16:03 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:03 INFO - '_': '/tools/buildbot/bin/python'} 18:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:04 INFO - Downloading blobuploader-1.2.4.tar.gz 18:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:16:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:16: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 18:16: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 18:16: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 18:16: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 18:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:16:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:16: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 18:16: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 18:16: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 18:16: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 18:16:04 INFO - Downloading docopt-0.6.1.tar.gz 18:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:16:05 INFO - Installing collected packages: blobuploader, requests, docopt 18:16:05 INFO - Running setup.py install for blobuploader 18:16:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:16:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:16:05 INFO - Running setup.py install for requests 18:16:05 INFO - Running setup.py install for docopt 18:16:05 INFO - Successfully installed blobuploader requests docopt 18:16:05 INFO - Cleaning up... 18:16:05 INFO - Return code: 0 18:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:05 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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:05 INFO - Using env: {'DISPLAY': ':0', 18:16:05 INFO - 'HOME': '/home/cltbld', 18:16:05 INFO - 'LANG': 'en_US.UTF-8', 18:16:05 INFO - 'LANGUAGE': 'en_US:en', 18:16:05 INFO - 'LOGNAME': 'cltbld', 18:16:05 INFO - 'MAIL': '/var/mail/cltbld', 18:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:05 INFO - 'MOZ_NO_REMOTE': '1', 18:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:05 INFO - 'NO_EM_RESTART': '1', 18:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:05 INFO - 'PWD': '/builds/slave/test', 18:16:05 INFO - 'SHELL': '/bin/bash', 18:16:05 INFO - 'SHLVL': '1', 18:16:05 INFO - 'TERM': 'linux', 18:16:05 INFO - 'TMOUT': '86400', 18:16:05 INFO - 'USER': 'cltbld', 18:16:05 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:05 INFO - '_': '/tools/buildbot/bin/python'} 18:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:05 INFO - Running setup.py (path:/tmp/pip-ML2roE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:05 INFO - Running setup.py (path:/tmp/pip-gLSzYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:05 INFO - Running setup.py (path:/tmp/pip-5p8SnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:06 INFO - Running setup.py (path:/tmp/pip-cS2_jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:06 INFO - Running setup.py (path:/tmp/pip-XDal1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:06 INFO - Running setup.py (path:/tmp/pip-kwQ2Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:06 INFO - Running setup.py (path:/tmp/pip-0xxgF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:06 INFO - Running setup.py (path:/tmp/pip-La8b0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:06 INFO - Running setup.py (path:/tmp/pip-BKLyxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:06 INFO - Running setup.py (path:/tmp/pip-a2WULe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:06 INFO - Running setup.py (path:/tmp/pip-DV9fNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:06 INFO - Running setup.py (path:/tmp/pip-oOscgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:06 INFO - Running setup.py (path:/tmp/pip-2sfcwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:06 INFO - Running setup.py (path:/tmp/pip-flAebf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:06 INFO - Running setup.py (path:/tmp/pip-t_Q7HM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:06 INFO - Running setup.py (path:/tmp/pip-lkEfZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:06 INFO - Running setup.py (path:/tmp/pip-uiv2q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:16:07 INFO - Running setup.py install for manifestparser 18:16:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Running setup.py install for mozcrash 18:16:07 INFO - Running setup.py install for mozdebug 18:16:07 INFO - Running setup.py install for mozdevice 18:16:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Running setup.py install for mozfile 18:16:07 INFO - Running setup.py install for mozhttpd 18:16:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Running setup.py install for mozinfo 18:16:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Running setup.py install for mozInstall 18:16:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:16:07 INFO - Running setup.py install for mozleak 18:16:07 INFO - Running setup.py install for mozlog 18:16:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Running setup.py install for moznetwork 18:16:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Running setup.py install for mozprocess 18:16:08 INFO - Running setup.py install for mozprofile 18:16:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Running setup.py install for mozrunner 18:16:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Running setup.py install for mozscreenshot 18:16:08 INFO - Running setup.py install for moztest 18:16:08 INFO - Running setup.py install for mozversion 18:16:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:16:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:16:08 INFO - Cleaning up... 18:16:08 INFO - Return code: 0 18:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:08 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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:08 INFO - Using env: {'DISPLAY': ':0', 18:16:08 INFO - 'HOME': '/home/cltbld', 18:16:08 INFO - 'LANG': 'en_US.UTF-8', 18:16:08 INFO - 'LANGUAGE': 'en_US:en', 18:16:08 INFO - 'LOGNAME': 'cltbld', 18:16:08 INFO - 'MAIL': '/var/mail/cltbld', 18:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:08 INFO - 'MOZ_NO_REMOTE': '1', 18:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:08 INFO - 'NO_EM_RESTART': '1', 18:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:08 INFO - 'PWD': '/builds/slave/test', 18:16:08 INFO - 'SHELL': '/bin/bash', 18:16:08 INFO - 'SHLVL': '1', 18:16:08 INFO - 'TERM': 'linux', 18:16:08 INFO - 'TMOUT': '86400', 18:16:08 INFO - 'USER': 'cltbld', 18:16:08 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:08 INFO - '_': '/tools/buildbot/bin/python'} 18:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:09 INFO - Running setup.py (path:/tmp/pip-RCLrhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:09 INFO - Running setup.py (path:/tmp/pip-2b9rZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:09 INFO - Running setup.py (path:/tmp/pip-hczONJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:09 INFO - Running setup.py (path:/tmp/pip-owPM0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:09 INFO - Running setup.py (path:/tmp/pip-6NvALx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:09 INFO - Running setup.py (path:/tmp/pip-es4WIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:09 INFO - Running setup.py (path:/tmp/pip-T4YDCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:09 INFO - Running setup.py (path:/tmp/pip-KVzHR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:09 INFO - Running setup.py (path:/tmp/pip-ehPA2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:09 INFO - Running setup.py (path:/tmp/pip-TErMph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:09 INFO - Running setup.py (path:/tmp/pip-Uebf6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:09 INFO - Running setup.py (path:/tmp/pip-0FZKFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:09 INFO - Running setup.py (path:/tmp/pip-vFCZlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:09 INFO - Running setup.py (path:/tmp/pip-7ZXWLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:10 INFO - Running setup.py (path:/tmp/pip-0kbfh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:10 INFO - Running setup.py (path:/tmp/pip-Nn2m13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:10 INFO - Running setup.py (path:/tmp/pip-h7POwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:16:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16: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 18:16: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 18:16: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 18:16: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 18:16:11 INFO - Downloading blessings-1.6.tar.gz 18:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:11 INFO - Installing collected packages: blessings 18:16:11 INFO - Running setup.py install for blessings 18:16:11 INFO - Successfully installed blessings 18:16:11 INFO - Cleaning up... 18:16:11 INFO - Return code: 0 18:16:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:11 INFO - Reading from file tmpfile_stdout 18:16:11 INFO - Current package versions: 18:16:11 INFO - argparse == 1.2.1 18:16:11 INFO - blessings == 1.6 18:16:11 INFO - blobuploader == 1.2.4 18:16:11 INFO - docopt == 0.6.1 18:16:11 INFO - functools32 == 3.2.3-2 18:16:11 INFO - jsonschema == 2.5.1 18:16:11 INFO - manifestparser == 1.1 18:16:11 INFO - mozInstall == 1.12 18:16:11 INFO - mozcrash == 1.0 18:16:11 INFO - mozdebug == 0.1 18:16:11 INFO - mozdevice == 0.51 18:16:11 INFO - mozfile == 1.2 18:16:11 INFO - mozhttpd == 0.7 18:16:11 INFO - mozinfo == 0.10 18:16:11 INFO - mozleak == 0.1 18:16:11 INFO - mozlog == 3.4 18:16:11 INFO - moznetwork == 0.27 18:16:11 INFO - mozprocess == 0.25 18:16:11 INFO - mozprofile == 0.28 18:16:11 INFO - mozrunner == 6.13 18:16:11 INFO - mozscreenshot == 0.1 18:16:11 INFO - mozsystemmonitor == 0.3 18:16:11 INFO - moztest == 0.8 18:16:11 INFO - mozversion == 1.4 18:16:11 INFO - psutil == 3.1.1 18:16:11 INFO - requests == 1.2.3 18:16:11 INFO - wsgiref == 0.1.2 18:16:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:11 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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:11 INFO - Using env: {'DISPLAY': ':0', 18:16:11 INFO - 'HOME': '/home/cltbld', 18:16:11 INFO - 'LANG': 'en_US.UTF-8', 18:16:11 INFO - 'LANGUAGE': 'en_US:en', 18:16:11 INFO - 'LOGNAME': 'cltbld', 18:16:11 INFO - 'MAIL': '/var/mail/cltbld', 18:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:11 INFO - 'MOZ_NO_REMOTE': '1', 18:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:11 INFO - 'NO_EM_RESTART': '1', 18:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:11 INFO - 'PWD': '/builds/slave/test', 18:16:11 INFO - 'SHELL': '/bin/bash', 18:16:11 INFO - 'SHLVL': '1', 18:16:11 INFO - 'TERM': 'linux', 18:16:11 INFO - 'TMOUT': '86400', 18:16:11 INFO - 'USER': 'cltbld', 18:16:11 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:11 INFO - '_': '/tools/buildbot/bin/python'} 18:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:16:12 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:13 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:16:13 INFO - Installing collected packages: marionette-driver 18:16:13 INFO - Running setup.py install for marionette-driver 18:16:13 INFO - Successfully installed marionette-driver 18:16:13 INFO - Cleaning up... 18:16:13 INFO - Return code: 0 18:16:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:13 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 0xff13b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x136fdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, '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': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:16:13 INFO - Using env: {'DISPLAY': ':0', 18:16:13 INFO - 'HOME': '/home/cltbld', 18:16:13 INFO - 'LANG': 'en_US.UTF-8', 18:16:13 INFO - 'LANGUAGE': 'en_US:en', 18:16:13 INFO - 'LOGNAME': 'cltbld', 18:16:13 INFO - 'MAIL': '/var/mail/cltbld', 18:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:13 INFO - 'MOZ_NO_REMOTE': '1', 18:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:13 INFO - 'NO_EM_RESTART': '1', 18:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:13 INFO - 'PWD': '/builds/slave/test', 18:16:13 INFO - 'SHELL': '/bin/bash', 18:16:13 INFO - 'SHLVL': '1', 18:16:13 INFO - 'TERM': 'linux', 18:16:13 INFO - 'TMOUT': '86400', 18:16:13 INFO - 'USER': 'cltbld', 18:16:13 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:13 INFO - '_': '/tools/buildbot/bin/python'} 18:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:16:13 INFO - Cleaning up... 18:16:13 INFO - Return code: 0 18:16:13 INFO - Running post-action listener: _resource_record_post_action 18:16:13 INFO - Running post-action listener: _start_resource_monitoring 18:16:13 INFO - Starting resource monitoring. 18:16:13 INFO - [mozharness: 2017-06-06 01:16:13.777275Z] Finished create-virtualenv step (success) 18:16:13 INFO - [mozharness: 2017-06-06 01:16:13.777788Z] Running install step. 18:16:13 INFO - Running pre-action listener: _resource_record_pre_action 18:16:13 INFO - Running main action method: install 18:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:13 INFO - Reading from file tmpfile_stdout 18:16:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:14 INFO - Reading from file tmpfile_stdout 18:16:14 INFO - Output received: 18:16:14 INFO - Usage: mozinstall [options] installer 18:16:14 INFO - Options: 18:16:14 INFO - -h, --help show this help message and exit 18:16:14 INFO - -d DEST, --destination=DEST 18:16:14 INFO - Directory to install application into. [default: 18:16:14 INFO - "/builds/slave/test"] 18:16:14 INFO - --app=APP Application being installed. [default: firefox] 18:16:14 INFO - mkdir: /builds/slave/test/build/application 18:16:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:16:31 INFO - Reading from file tmpfile_stdout 18:16:31 INFO - Output received: 18:16:31 INFO - /builds/slave/test/build/application/firefox/firefox 18:16:31 INFO - Running post-action listener: _resource_record_post_action 18:16:31 INFO - [mozharness: 2017-06-06 01:16:31.533748Z] Finished install step (success) 18:16:31 INFO - [mozharness: 2017-06-06 01:16:31.534024Z] Running setup-mitmproxy step. 18:16:31 INFO - Running pre-action listener: _resource_record_pre_action 18:16:31 INFO - Running main action method: setup_mitmproxy 18:16:31 INFO - Skipping: mitmproxy is not required 18:16:31 INFO - Running post-action listener: _resource_record_post_action 18:16:31 INFO - [mozharness: 2017-06-06 01:16:31.535192Z] Finished setup-mitmproxy step (success) 18:16:31 INFO - [mozharness: 2017-06-06 01:16:31.535410Z] Running run-tests step. 18:16:31 INFO - Running pre-action listener: _resource_record_pre_action 18:16:31 INFO - Running main action method: run_tests 18:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:16:31 INFO - Python 2.7.3 18:16:31 INFO - Return code: 0 18:16:31 INFO - grabbing minidump binary from tooltool 18:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:31 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136c840>, '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 0x136d740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x136dbd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:16:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:16:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:16:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:16:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:16:31 INFO - Return code: 0 18:16:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:16:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:16:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:16:31 INFO - ENV: RUST_BACKTRACE is now 1 18:16:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:16:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:16:31 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/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:16:31 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/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-080 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:16:31 INFO - Using env: {'DISPLAY': ':0', 18:16:31 INFO - 'HOME': '/home/cltbld', 18:16:31 INFO - 'LANG': 'en_US.UTF-8', 18:16:31 INFO - 'LANGUAGE': 'en_US:en', 18:16:31 INFO - 'LOGNAME': 'cltbld', 18:16:31 INFO - 'MAIL': '/var/mail/cltbld', 18:16:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:16:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:31 INFO - 'MOZ_NO_REMOTE': '1', 18:16:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:31 INFO - 'NO_EM_RESTART': '1', 18:16:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:31 INFO - 'PWD': '/builds/slave/test', 18:16:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:16:31 INFO - 'RUST_BACKTRACE': '1', 18:16:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:16:31 INFO - 'SHELL': '/bin/bash', 18:16:31 INFO - 'SHLVL': '1', 18:16:31 INFO - 'TERM': 'linux', 18:16:31 INFO - 'TMOUT': '86400', 18:16:31 INFO - 'USER': 'cltbld', 18:16:31 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852', 18:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:31 INFO - '_': '/tools/buildbot/bin/python'} 18:16:31 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/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:16:31 INFO - mozversion application_buildid: 20170606003417 18:16:31 INFO - mozversion application_changeset: 2c6289f56812c30254acfdddabcfec1e149c0336 18:16:31 INFO - mozversion application_display_name: Nightly 18:16:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:16:31 INFO - mozversion application_name: Firefox 18:16:31 INFO - mozversion application_remotingname: firefox 18:16:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:16:31 INFO - mozversion application_vendor: Mozilla 18:16:31 INFO - mozversion application_version: 55.0a1 18:16:31 INFO - mozversion platform_buildid: 20170606003417 18:16:31 INFO - mozversion platform_changeset: 2c6289f56812c30254acfdddabcfec1e149c0336 18:16:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:16:31 INFO - mozversion platform_version: 55.0a1 18:16:31 INFO - using testdate: 1496711791 18:16:31 INFO - actual date: 1496711791 18:16:31 INFO - starting webserver on 'localhost:41227' 18:16:31 INFO - SUITE-START | Running 2 tests 18:16:31 INFO - TEST-START | dromaeo_css 18:16:31 INFO - operating with platform_type : linux_ 18:16:31 INFO - Initialising browser for dromaeo_css test... 18:16:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41227/getInfo.html -profile /tmp/tmpNEX_6X/profile 18:16:31 INFO - TEST-INFO | started process 5991 (/builds/slave/test/build/application/firefox/firefox http://localhost:41227/getInfo.html) 18:17:07 INFO - TEST-INFO | 5991: killed by SIGTERM 18:17:07 INFO - Browser initialized. 18:17:07 INFO - Running cycle 1/1 for dromaeo_css test... 18:17:07 INFO - TEST-INFO | started process 6222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNEX_6X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:18 INFO - PID 6222 | RSS: Main: 200835072 18:17:18 INFO - PID 6222 | 18:20:20 INFO - PID 6222 | Cycle 1(1): loaded http://localhost:41227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41227/tests/dromaeo/cssquery-ext.html) 18:20:21 INFO - PID 6222 | RSS: Main: 187154432 18:20:21 INFO - PID 6222 | 18:23:23 INFO - PID 6222 | Cycle 1(1): loaded http://localhost:41227/tests/dromaeo/cssquery-ext.html (next: http://localhost:41227/tests/dromaeo/cssquery-jquery.html) 18:23:24 INFO - PID 6222 | RSS: Main: 193835008 18:23:24 INFO - PID 6222 | 18:26:26 INFO - PID 6222 | Cycle 1(1): loaded http://localhost:41227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41227/tests/dromaeo/cssquery-mootools.html) 18:26:26 INFO - PID 6222 | RSS: Main: 191029248 18:26:26 INFO - PID 6222 | 18:29:28 INFO - PID 6222 | Cycle 1(1): loaded http://localhost:41227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41227/tests/dromaeo/cssquery-prototype.html) 18:29:29 INFO - PID 6222 | RSS: Main: 195260416 18:29:29 INFO - PID 6222 | 18:32:32 INFO - PID 6222 | Cycle 1(1): loaded http://localhost:41227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41227/tests/dromaeo/cssquery-yui.html) 18:32:32 INFO - PID 6222 | RSS: Main: 194076672 18:32:32 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | Cycle 1(1): loaded http://localhost:41227/tests/dromaeo/cssquery-yui.html (next: http://localhost:41227/tests/dromaeo/cssquery-dojo.html) 18:35:34 INFO - PID 6222 | RSS: Main: 193359872 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | __start_tp_report 18:35:34 INFO - PID 6222 | _x_x_mozilla_page_load 18:35:34 INFO - PID 6222 | _x_x_mozilla_page_load_details 18:35:34 INFO - PID 6222 | |i|pagename|runs| 18:35:34 INFO - PID 6222 | |0;dojo.html;3112.579801726767;3121.282105115825;3144.434001879662;3160.478521044029;3165.446046941786;5884.293884733827;6037.064255040465;6039.72821223045;6065.666388348639;6079.05774604937;5948.999999999989;5957.40425957404;6012.428819262163;6039.818805435833;6111.7249723762425;5505.146702261136;5601.579881508883;5605.943940560583;5621.184928185411;5642.463965923252;5639.915401268984;5666.121751128581;5680.431956804307;5682.232898558704;5730.226419433386;5482.205080263339;5506.421825708323;5537.335519737658;5571.777128914839;5600.467955544217;5580.135079062752;5616.550675945915;5618;5622.91565626516;5662.348829884575;5501.972490137564;5547.1124620061;5559.444055594448;5578.107502799573;5585;6028.517718582503;6045.486133678643;6056.636601803906;6068.726907289182;6069.57512974092;6345.777897773556;6398.552101352907;6422.4862011039;6473.74105035798;6528.249251336109;6618.635975021371;6620.23867255267;6633.905405608069;6636.336366363325;6647.933520664828;6120.357362476965;6128.050152226412;6130.846728831771;6134.17188679527;6142.78500252489;6522.804315870531;6620.073189753439;6629.767958121539;6702.932970670328;6720.462363010947;6155.815325540241;6234.1583886175695;6356.713947872357;6413;6437.452816510649;6293.056041593798;6378.489720822416;6391.744330226832;6400.0719895614875;6401.3278605746655;6412.487001039905;6523.7716679915975;6527.771527996592;6548.934510654833;6559.573627714183;6273.811785646438;6380.298167201604;6429.421352078335;6556.98366753154;6565.934340656628;5342.91985620216;5352.464753524617;5367.114426119724;5370.2213179089595;5388.191771234267;4534.478534968455;4548.226801443762;4551.931721024187;4567.132244873464;4636.165490211794;5580.609357345028;5643.5767317451355;5646.915296270559;5651.6043876928225;5654.462826031603;6481.157449531531;6493.902591461132;6549.967250163719;6564.343565643493;6566.770163044366;5660.858478537987;5682.7442765075675;5686.402927692616;5697.4302569742695;5703.230063941315;5773.364929857712;5824.330202026821;5826.329972053183;5830.47525722687;5841.561882858718;6154.59995100326;6203.31763506023;6247;6318.873622527615;6331.715072821642;6599.703013364506;6609.867802644017;6615.272320044887;6616.768413105518;6626.304238055128;6387.457066149429;6389.329120442287;6392.137061496825;6404.391582799627;6406.743730250738;6236.314005459305;6298.590591611531;6320.905186422299;6380.298167201511;6441.967790161019;6532.934670653232;6565.474762018932;6582.275949645439;6621.039949207434;6623.437007854193;6159.692015399301;6373.490120790419;6379.585326953733;6380.042993551005;6395.1046853441485;6268.153799237227;6275.3724627536885;6378.394052564999;6397.200349956255;6399.64801935898;6260.7495700171485;6273.874522509433;6286.057091436138;6299.622022678654;6314.1160237565855;6316.715747791269;6394.648294343856;6409.070684750778;6412.807615771534;6422.903656445251;6103.05402662588;6143.665407145256;6153.230846144232;6189.133521306931;6221.035739460391 18:35:34 INFO - PID 6222 | |1;ext.html;733.7065173930428;773.7562667759655;800.4076983032555;1149.2357582207833;1171.0982543441544;36255.199168133106;36952.07619809503;37024.25951480969;37102.62897371022;37105.25789484216;39053.21893562127;39172.41241381374;39389;39568.208635827265;39587;7402.962985185067;7444.516106453077;7503;7576.772696819105;7580.772576822704;7583.62081895903;7696.6536505857375;7703.268189522015;7709.267619576131;7711.383089352838;7644.235576442366;7644.885326720104;7664.080310362764;7693.384529237647;7721.613919304041;7635.312821846032;7637.961810190914;7646;7650.540967541965;7664.65509052094;6878.759243426456;6905.930940690579;6918.235849858254;6982.546134501292;6989.685464154125;35512.467312990324;35739.64260357389;35753.64246357529;35793.64206357929;35830;3553.466979953002;3556.697680697144;3607.0441333046765;3614;3616.33097876891;28299.575506367422;28325.43349133006;28617.14148575525;28700.569491457645;28726.856365718246;3666.0651533858695;3668.1746607013474;3692.2061756722164;3704.9629503704887;3709.870154544578;142800.28599856934;144236;153601;167201;167745;43992.12015759731;44470;44474;44508.77745611316;44612;46313.76843115763;46438;46510.651275103795;46611.766941165755;46743.29885051727;14981.400743970336;15037.548873533811;15108.471203507825;15165.24173791328;15239.923800380928;12271.263724176399;12425.19236249641;12438.875611243953;12464.81302780459;12478.937605311916;13799.103058301178;13800.93099534516;13863.168209907439;13943.16341019542;14033.71932561343;5699.943000570024;5747.195392645033;5748.511376532958;5844.678542680108;5873.471387575053;975.8145952269047;998.7602975285879;1002.293383164867;1003.5383723487278;1008.9545970431349;21534.353969380943;22434.439139021328;22480.43798905008;22942.770572294397;23141.652875206884;1076.4617691154422;1076.5101878645198;1084.5065495199665;1090.3512410115968;1094.8795632480421;11423.314601123959;11911.118978428736;11963.700907477209;12342.506299748089;12888.742225155633;194299;194482;194726;194874;195657;49042.75478622656;49242.507574923795;49549.75225123923;49614.25578616324;49758;1429.2639290765264;1459.1172340733915;1461.3789139615621;1477.7487827069208;1478.4899209772611;20692;22216.444588884762;23164.189253376724;23473.530529388976;23541.17605883786;35599.46600800938;35607;35788.46317305294;35914.46128308025;35922.10194745152;163409;163523;164111;164150.17924910298;164816;31375.37249255048;32322.83838580792;32418.513722294658;32877.178070547474;32900;40067.599324007555;40545;41070;41378.27595516418;41860;29377.265568360108;30188.69811301859;30402;30595.23511912131;31451.52822707707;30178.54732179063;30340.84829575838;30471.14906559798;30498.695013049586;31898.52152217765;33228.33543329168;33851.32297354088;34011.14972125716;34094.31811363709;34151.658483414845;29323.853380733814;30464.390712185188;30794.692053079183;31144;31407.979483854284;23684.763152368945;24826.875865620557;24872.12947546827;24987.50024999526;25108.12121575736 18:35:34 INFO - PID 6222 | |2;jquery.html;411.90526178978854;414.8195534942302;427.7412165639788;430.9353596960457;440.4868328397415;14641.267936603153;15307.081575105502;20459.18163273469;21177.682334764973;21243.468913277175;20776.8961155194;21715.565688686216;21741.565168696616;21799.67300490494;21808.345749627537;5954.285485741717;6111;6117;6130.264368275791;6139.508839292868;5965.105234214859;6042.214512113441;6078.908816367736;6078.939210607904;6085.178500902388;5958.7616495340135;5990.191324171246;6003.369646187153;6288.9056664150075;6623.105880706091;6650.700718467657;6682.365175308338;6707.463402927754;6709.396154346084;6736.764213252537;6574.309697481791;6599.142111525519;6609.6695165241545;6618.437432818217;6622.238442579117;26127.869360653265;26544;26845.03366877134;26932.326691832666;26958.73041269582;1950.980490195094;1999.9900050474562;2004.83961283097;2006.7491563554556;2007.5583371658213;17609.647807043915;18081.09594520282;18093.819061809347;18114.275428982826;18124.90937545317;2394.892229849661;2413.9034438622434;2427.720812106595;2428.9028438862424;2483.962740558893;118813;119623;121338;121416;122391.3880430601;18868.33960811365;19769.209231630575;20093.995300235223;20113;20573.279935202198;36909.46358046261;37090.814545927096;37281.25437491235;37355.81322093372;37362;10568.471576421149;10683.30558513694;10707.571697132184;10714.624988125379;10916.471693962852;8083.19168083187;8566.100559441293;8699.391042627081;8797.252233560092;8831.337649676172;7616.771496855104;7764.961175194087;7842.176571460029;7848.568328741974;7938.841223175504;2095.6751703485984;2114.365690292906;2136.1775716349316;2165.588538177741;2245.719285089364;899.8020435504178;907.9773005674779;923.8706581078654;956.31623389277;976.3556053004983;17966.910165449088;17993.370232041816;18333.908330458264;19040.52398690044;19646.607067858848;997.2520609542843;998.3311181508407;1016.4866742295094;1020.6008327784896;1021.9662870886882;10040.246981476419;10073.69778906634;10382.273240873066;10788.622398216025;10967.122630189566;403701;409914;410067;421606;424703;97329;99073.50463247638;99928.50035749775;100230.4988475053;100592.49703751435;1143.5940241214416;1162.0006794157018;1180.226951346869;1194.1760185471996;1196.1567095197865;15474;15820.841791581937;16082.195890205678;17127.91436042862;17147.914260428617;43908;45399.54600453953;48340.758296208296;49105.263421047996;49328.260076098166;59632.40367596268;63230.36769632245;66665.33334666591;66711.33221669497;67010.32989670041;10169.389836609827;10499.107575855833;10503.632372867218;10507.31702439339;10537.315074520131;17145.657086857944;17680.55798604994;17965.73051404204;18029;18097.90951045289;13550.59348219555;13701.657458563615;13712.451501939811;13725.176489410267;14293.499727509487;12754.48982040708;13080;13084;13357;13362;13522.86477135216;14378.496752613608;14480.565583032105;14528;14538;9975.755455022561;10025.298229124182;10127.544260508148;10186.134178594903;10244.487775611338;12800.61598152057;12916.41876115596;13009.674758131123;13022.674433139247;14061.086029408056 18:35:34 INFO - PID 6222 | |3;mootools.html;1305.4908585651597;1378.1868697468492;1379.92410417427;1390.582825152454;1397.6226418866902;10726.678199654014;10982.341059536433;11090.889091109077;11154.330740155596;11566.53733850645;11284.774304513905;11505.07959363251;11516.481758320875;11516.481758320875;11522.539098436073;2325.465143017103;2359.1860808021206;2373.22870067228;2373.3947843299934;2378.8096952243864;2240.2271216430386;2292.518571100071;2301.3786277705094;2305.5849947009533;2312.491251924574;2223.7442694090146;2242.517858660388;2245.6182448983714;2252.1666983216232;2259.378670865509;2144.6997420361163;2208.4368486036037;2209.06114901168;2213.612617791888;2217.0466699319286;1878.7839398469214;1923.692209246528;2159.9568008639894;2166.1876796201423;2173.6413491773997;10859.891401085966;10929.890701092967;10955.83566246507;10964.342139471657;10973.177011724072;6202.28673702521;6436.131122298477;6440.194975628046;6443.259025212114;6465.870682586369;3607.350676878161;3799.411091280856;3837.6162383761402;3892.1048158923604;3893.085124995652;1323.4573824731813;1329.9268540230285;1340.537514557476;1349.6355983884393;1354.945802167912;15898.920505397398;15923.52229433119;15941;16045.679086418206;16093.999999999884;5209.29674493946;5211.5830733541225;5257.842264732064;5409.269033909111;5411.404745477994;12315.568955086528;12325.753484930252;12328.50685972551;12335.074869384833;12354.938225308815;6162.7226774795245;6309.558330916883;6312.936870631235;6321.494280457552;6326.114343991845;5775.047117225695;5799.594028418054;5848.795292164839;5872.17789509469;5896.528277737771;4187.937180942288;4208.726432781859;4275.29457639486;4277.422547955986;4321.157374311979;1203.6569577670498;1223.1865809236758;1237.307108019512;1244.6266120163914;1248.8688687687843;836.1554829622004;849.76631426357;861.1130535548396;865.1391865094272;887.6316328723636;5760.769569217185;5897.056470964625;5969.671668058211;6002.249718785152;6124.724387402578;594.6075590110593;601.6570554783731;613.3406587918006;635.2504045226584;641.9775307864203;3779.225258821935;3917.804109794556;4217.283061879488;4273.7008409411665;4323.718958267703;13617.591472255848;14201.28993550339;14264.144151350953;14283.14301141914;14293;13679.589612311647;13916;14051.929740351234;14058.297085145701;14098.365573549216;8285;8442.746717598358;8603.913960860436;8752.56237188138;8895.332850036402;6286.528510361833;6373.681315934092;6560.671966401756;6649.534532582673;6772.830679233059;12645.810312845131;12720.74558508843;12846.743065138458;12855.485780568673;13031.283279419724;12777.55278565283;12796.296203708886;12840.293783841978;12954.935225323814;13149.60551183466;9399.906000940176;9519.238460923249;9528.380655257386;9539.523023848918;9602.279829012712;10326.48367581633;10375.118114960313;10494;10654.414007229676;10704;8806;8823.955880220557;8830.072842351461;8848.292136628925;8867.512286824287;7971.720989765562;8088.231617996281;8144.918550814416;8160.347172226327;8262.42163048581;7026.929730702627;7262.96368518154;7300.853982920205;7368.705251790083;7463.141738699902;5317.867053323698;5680.091185410314;5770.13447982806;5804.158397032322;5825.70871456434;5924.259467566554;5999.460048595647;6038.184845045863;6326.778562750282;6345.7144428499905 18:35:34 INFO - PID 6222 | |4;prototype.html;393.5199057150277;401.5001323352425;402.30803018807643;402.7717841500375;403.95960403959606;6190.226221722285;6280.528960327976;6288.811335659926;6296.016754722265;6355.936440635593;5987.88024239516;6090.8073093686235;6100.908486372708;6114.449699527042;6148.985417406123;4629.768511574408;4779.450363208223;4794.640401969848;4799.880002999918;4801.6878902871285;4818.277258411232;4826.155422801014;4829.637777166709;4842.588379987706;4845.825983980499;4768.427788665365;4779.498152693969;4786.4974177711365;4788.1086970646575;4814.1575224335775;4773.331733557305;4797.208460603996;4797.999999999983;4803.591694705954;4816.109019831308;4198.6011328923705;4206.894827629303;4218.578142185787;4223.598758117974;4226.260404429243;6070.332263451061;6103.938960610381;6108.267007959073;6110.8777824443705;6111.655435804116;5483.835484935458;5499.890002199933;5513.448655134494;5520.089185284423;5521.917171242394;6526.477881769494;6541.672916354163;6543.658549997287;6549.0503876938055;6563.54055216135;4589.265717485219;4596.770161491912;4608.654350923661;4608.778673651452;4611.723296602181;6497.545171837974;6889.345512176335;6928.584284942869;6983.580985140857;7001.124859392525;4546.613537849254;4557.088582283556;4570.360149579062;4578.397560853675;4588.174128656807;4646.233371493666;4653.371794807726;4653.650976176733;4655.464621568495;4668.385704005767;5272.472752724697;5382.192671099288;5418.6748795071635;5430.701311427831;5448.91826622601;6570.408663220333;6595.70319335631;6603.240627327918;6609.438197753245;6618.569792963442;1625.390478570536;1628.9981661278227;1631.7633943078188;1634.182908545727;1648.3159488812248;1060.8090543702053;1081.567373050786;1094.4308959341258;1096.1997741648481;1104.089126470665;5767.769289228384;5767.971160144172;5776.393478684761;5801.361850196475;5804.593678442468;6766.560173588701;6778.627175505395;6802.727890884408;6817.181938167451;6820.420264277592;5712.143178523172;5769.365369809318;5805.88388232233;5837.211976383134;5844.444777746107;3596.6403359663823;3600.1719604491113;3621.167131559756;3627.367684541914;3640.381135207021;6484.1894763154605;6520.9347906520325;6524.478041756648;6543.836404089936;6622.337766223339;6966.895496567556;6967.407770339578;6976.965115174392;6983;7014.894776578355;6536.934630653727;6579.47364210862;6581.868362632817;6589.703463344161;6629.370209830058;6769.679912417031;6800.761973330909;6801.9319806801295;6807.897881531682;6824.556403833735;6703;6713.798586042427;6730.158730158731;6753.628550429774;6758.42553382968;6135.233095863017;6471.805845824632;6571.901421478586;6581.308962558863;6634.900476492953;4593.127305811885;4603.631709463168;4635.745034023161;4642.582167604932;4662.603678687214;6726;6736.5958042517605;6768.56004359715;6772.002259333607;6797.320267973163;6382.893685051847;6532.5753826001155;6539.313372095861;6551.279359270381;6593.736250550116;6550.8362290943105;6571.507136964651;6578.407943285128;6579.835504112435;6581.835454113685;6431.453326467303;6460.547761656639;6479.676016199265;6531.9020214695865;6556.508261880283;6626.403623673892;6627.701753421204;6631.469482441296;6639.834004149935;6667.799966001027;6354.586951848115;6438.839029024124;6445.613263204223;6454.548181627241;6454.612723236621 18:35:34 INFO - PID 6222 | |5;yui.html;821.9054808697001;851.8637018077106;858.6737039924832;862.8179394229902;873.7422460374189;16646.667066658658;17019.234134463946;17454.650906981868;17509.29962801488;18842.81157188428;17410.912945435255;17449.563760905952;17568.560785980324;17623.383181588648;17696.734548981774;890.1365675294953;919.9926080941359;925.676013395313;926.7080869526111;927.2535608834885;956.3162338927665;987.239825334492;997.0727223681973;1001.8096561653299;1002.6941782756256;1037.2946396450411;1039.6205385034445;1041.8958104189596;1045.7124290820047;1045.9320144190642;1031.1853635627883;1032.4682788363998;1033.178622994721;1033.3076838518211;1037.3931250218632;758.4690716498418;765.0207734100361;765.5521519910803;766.7853001159684;767.0909971683573;15886.205689715467;16035.839641603552;16061.357545698158;16084.75872861908;16093.839061609351;18608.90695546527;18703.34538291167;19065;19117.61764764711;19139.61720765591;13986.720265594631;14149.858501414958;14218.217998010105;14246.786298205536;14299.857001429957;3450.8102054387;3459.7232221422228;3501.9124521887156;3504.8072356020407;3509.2279698466295;89514.5524272381;91475.54262228646;92306;92491.53754231254;93457;14599.854001460062;14703.338349774285;14859.108453492825;14864.851351486563;14924.552263432115;27154.321141971213;27366.452670946866;27881;27912.162635120978;28175.577366339523;8103.1491693371345;8196.54918979462;8402.201790829862;8452.28155606768;8478.660853565912;3990.1221731219084;4088.8773336799945;4111.9177616447505;4112.629863312316;4113.115680128787;4226.281532139544;4319.157764235943;4329.783510824446;4358.084802191512;4383.232934236496;814.7026335387541;839.8572242718752;843.2495079379357;843.5697794125041;851.3317046118767;657.5298661457081;679.4937771360368;688.1501345837886;691.7475121580587;698.8287869472011;10114.190864730805;10239.18086553074;10649.201309901788;10764.3541387517;11012;656.1240743607337;672.4351544702473;679.341039191983;680.7787469072472;685.7325642999234;4836.564709176191;4887.242477416006;4902.117618828645;5015.1975683890405;5113.15632920564;90838;93059;95435;96939;97310;77655;80926;81166.59416702879;81209.59395202986;85989.57005214934;68298.31701683019;69448.65275673589;69754;70172.73704472186;71132.2886771136;10234.692959211236;10319.793604127724;10609.51466794634;10638.095761860019;11124.05445537106;28549.28626784264;28703.712962870104;28866;28869;30138.09585712432;54695.72652136714;55100.72449637726;56065.719671401384;56851.86296274134;56873.43126568847;16487.34050637961;16719.331226750794;16736.246868891172;16804.747928781322;16990.49028529146;17593.472195834147;18206.18072186781;18452.169652365945;18459.538511537317;18802.247910083443;14612.41550338017;14616.853831461549;14747.92626036863;14947.25263736787;15153.166575838435;15125.697486049998;15130.848691513384;15266.694666106838;15389.769153462483;17451.650966980844;14524.201168935388;14545.237598067386;14673.779893301822;14846.18345990981;14852.257387130385;10320.64917671155;10895.509702063267;10958.616448424267;11163.665090047312;11290.830637540608;12734.61796146117;13285.269310188032;13310.534131305745;13355.198688078746;13369.66575835612 18:35:34 INFO - PID 6222 | __end_tp_report 18:35:34 INFO - PID 6222 | __start_cc_report 18:35:34 INFO - PID 6222 | _x_x_mozilla_cycle_collect,370 18:35:34 INFO - PID 6222 | __end_cc_report 18:35:34 INFO - PID 6222 | __startTimestamp1496712934848__endTimestamp 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | ------- Summary: start ------- 18:35:34 INFO - PID 6222 | Number of tests: 6 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | [#0] dojo.html Cycles:165 Average:6000.28 Median:6203.32 stddev:683.94 (11.0%) stddev-sans-first:647.44 18:35:34 INFO - PID 6222 | Values: 3112.6 3121.3 3144.4 3160.5 3165.4 5884.3 6037.1 6039.7 6065.7 6079.1 5949.0 5957.4 6012.4 6039.8 6111.7 5505.1 5601.6 5605.9 5621.2 5642.5 5639.9 5666.1 5680.4 5682.2 5730.2 5482.2 5506.4 5537.3 5571.8 5600.5 5580.1 5616.6 5618.0 5622.9 5662.3 5502.0 5547.1 5559.4 5578.1 5585.0 6028.5 6045.5 6056.6 6068.7 6069.6 6345.8 6398.6 6422.5 6473.7 6528.2 6618.6 6620.2 6633.9 6636.3 6647.9 6120.4 6128.1 6130.8 6134.2 6142.8 6522.8 6620.1 6629.8 6702.9 6720.5 6155.8 6234.2 6356.7 6413.0 6437.5 6293.1 6378.5 6391.7 6400.1 6401.3 6412.5 6523.8 6527.8 6548.9 6559.6 6273.8 6380.3 6429.4 6557.0 6565.9 5342.9 5352.5 5367.1 5370.2 5388.2 4534.5 4548.2 4551.9 4567.1 4636.2 5580.6 5643.6 5646.9 5651.6 5654.5 6481.2 6493.9 6550.0 6564.3 6566.8 5660.9 5682.7 5686.4 5697.4 5703.2 5773.4 5824.3 5826.3 5830.5 5841.6 6154.6 6203.3 6247.0 6318.9 6331.7 6599.7 6609.9 6615.3 6616.8 6626.3 6387.5 6389.3 6392.1 6404.4 6406.7 6236.3 6298.6 6320.9 6380.3 6442.0 6532.9 6565.5 6582.3 6621.0 6623.4 6159.7 6373.5 6379.6 6380.0 6395.1 6268.2 6275.4 6378.4 6397.2 6399.6 6260.7 6273.9 6286.1 6299.6 6314.1 6316.7 6394.6 6409.1 6412.8 6422.9 6103.1 6143.7 6153.2 6189.1 6221.0 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | [#1] ext.html Cycles:180 Average:33550.34 Median:33539.83 stddev:44452.12 (132.5%) stddev-sans-first:44508.52 18:35:34 INFO - PID 6222 | Values: 733.7 773.8 800.4 1149.2 1171.1 36255.2 36952.1 37024.3 37102.6 37105.3 39053.2 39172.4 39389.0 39568.2 39587.0 7403.0 7444.5 7503.0 7576.8 7580.8 7583.6 7696.7 7703.3 7709.3 7711.4 7644.2 7644.9 7664.1 7693.4 7721.6 7635.3 7638.0 7646.0 7650.5 7664.7 6878.8 6905.9 6918.2 6982.5 6989.7 35512.5 35739.6 35753.6 35793.6 35830.0 3553.5 3556.7 3607.0 3614.0 3616.3 28299.6 28325.4 28617.1 28700.6 28726.9 3666.1 3668.2 3692.2 3705.0 3709.9 142800.3 144236.0 153601.0 167201.0 167745.0 43992.1 44470.0 44474.0 44508.8 44612.0 46313.8 46438.0 46510.7 46611.8 46743.3 14981.4 15037.5 15108.5 15165.2 15239.9 12271.3 12425.2 12438.9 12464.8 12478.9 13799.1 13800.9 13863.2 13943.2 14033.7 5699.9 5747.2 5748.5 5844.7 5873.5 975.8 998.8 1002.3 1003.5 1009.0 21534.4 22434.4 22480.4 22942.8 23141.7 1076.5 1076.5 1084.5 1090.4 1094.9 11423.3 11911.1 11963.7 12342.5 12888.7 194299.0 194482.0 194726.0 194874.0 195657.0 49042.8 49242.5 49549.8 49614.3 49758.0 1429.3 1459.1 1461.4 1477.7 1478.5 20692.0 22216.4 23164.2 23473.5 23541.2 35599.5 35607.0 35788.5 35914.5 35922.1 163409.0 163523.0 164111.0 164150.2 164816.0 31375.4 32322.8 32418.5 32877.2 32900.0 40067.6 40545.0 41070.0 41378.3 41860.0 29377.3 30188.7 30402.0 30595.2 31451.5 30178.5 30340.8 30471.1 30498.7 31898.5 33228.3 33851.3 34011.1 34094.3 34151.7 29323.9 30464.4 30794.7 31144.0 31408.0 23684.8 24826.9 24872.1 24987.5 25108.1 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | [#2] jquery.html Cycles:180 Average:30412.73 Median:21210.58 stddev:69943.42 (329.8%) stddev-sans-first:70103.36 18:35:34 INFO - PID 6222 | Values: 411.9 414.8 427.7 430.9 440.5 14641.3 15307.1 20459.2 21177.7 21243.5 20776.9 21715.6 21741.6 21799.7 21808.3 5954.3 6111.0 6117.0 6130.3 6139.5 5965.1 6042.2 6078.9 6078.9 6085.2 5958.8 5990.2 6003.4 6288.9 6623.1 6650.7 6682.4 6707.5 6709.4 6736.8 6574.3 6599.1 6609.7 6618.4 6622.2 26127.9 26544.0 26845.0 26932.3 26958.7 1951.0 2000.0 2004.8 2006.7 2007.6 17609.6 18081.1 18093.8 18114.3 18124.9 2394.9 2413.9 2427.7 2428.9 2484.0 118813.0 119623.0 121338.0 121416.0 122391.4 18868.3 19769.2 20094.0 20113.0 20573.3 36909.5 37090.8 37281.3 37355.8 37362.0 10568.5 10683.3 10707.6 10714.6 10916.5 8083.2 8566.1 8699.4 8797.3 8831.3 7616.8 7765.0 7842.2 7848.6 7938.8 2095.7 2114.4 2136.2 2165.6 2245.7 899.8 908.0 923.9 956.3 976.4 17966.9 17993.4 18333.9 19040.5 19646.6 997.3 998.3 1016.5 1020.6 1022.0 10040.2 10073.7 10382.3 10788.6 10967.1 403701.0 409914.0 410067.0 421606.0 424703.0 97329.0 99073.5 99928.5 100230.5 100592.5 1143.6 1162.0 1180.2 1194.2 1196.2 15474.0 15820.8 16082.2 17127.9 17147.9 43908.0 45399.5 48340.8 49105.3 49328.3 59632.4 63230.4 66665.3 66711.3 67010.3 10169.4 10499.1 10503.6 10507.3 10537.3 17145.7 17680.6 17965.7 18029.0 18097.9 13550.6 13701.7 13712.5 13725.2 14293.5 12754.5 13080.0 13084.0 13357.0 13362.0 13522.9 14378.5 14480.6 14528.0 14538.0 9975.8 10025.3 10127.5 10186.1 10244.5 12800.6 12916.4 13009.7 13022.7 14061.1 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | [#3] mootools.html Cycles:180 Average:6815.40 Median:3693.29 stddev:4354.92 (117.9%) stddev-sans-first:4347.45 18:35:34 INFO - PID 6222 | Values: 1305.5 1378.2 1379.9 1390.6 1397.6 10726.7 10982.3 11090.9 11154.3 11566.5 11284.8 11505.1 11516.5 11516.5 11522.5 2325.5 2359.2 2373.2 2373.4 2378.8 2240.2 2292.5 2301.4 2305.6 2312.5 2223.7 2242.5 2245.6 2252.2 2259.4 2144.7 2208.4 2209.1 2213.6 2217.0 1878.8 1923.7 2160.0 2166.2 2173.6 10859.9 10929.9 10955.8 10964.3 10973.2 6202.3 6436.1 6440.2 6443.3 6465.9 3607.4 3799.4 3837.6 3892.1 3893.1 1323.5 1329.9 1340.5 1349.6 1354.9 15898.9 15923.5 15941.0 16045.7 16094.0 5209.3 5211.6 5257.8 5409.3 5411.4 12315.6 12325.8 12328.5 12335.1 12354.9 6162.7 6309.6 6312.9 6321.5 6326.1 5775.0 5799.6 5848.8 5872.2 5896.5 4187.9 4208.7 4275.3 4277.4 4321.2 1203.7 1223.2 1237.3 1244.6 1248.9 836.2 849.8 861.1 865.1 887.6 5760.8 5897.1 5969.7 6002.2 6124.7 594.6 601.7 613.3 635.3 642.0 3779.2 3917.8 4217.3 4273.7 4323.7 13617.6 14201.3 14264.1 14283.1 14293.0 13679.6 13916.0 14051.9 14058.3 14098.4 8285.0 8442.7 8603.9 8752.6 8895.3 6286.5 6373.7 6560.7 6649.5 6772.8 12645.8 12720.7 12846.7 12855.5 13031.3 12777.6 12796.3 12840.3 12954.9 13149.6 9399.9 9519.2 9528.4 9539.5 9602.3 10326.5 10375.1 10494.0 10654.4 10704.0 8806.0 8824.0 8830.1 8848.3 8867.5 7971.7 8088.2 8144.9 8160.3 8262.4 7026.9 7263.0 7300.9 7368.7 7463.1 5317.9 5680.1 5770.1 5804.2 5825.7 5924.3 5999.5 6038.2 6326.8 6345.7 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | [#4] prototype.html Cycles:180 Average:5440.69 Median:6109.57 stddev:1616.99 (26.5%) stddev-sans-first:1576.52 18:35:34 INFO - PID 6222 | Values: 393.5 401.5 402.3 402.8 404.0 6190.2 6280.5 6288.8 6296.0 6355.9 5987.9 6090.8 6100.9 6114.4 6149.0 4629.8 4779.5 4794.6 4799.9 4801.7 4818.3 4826.2 4829.6 4842.6 4845.8 4768.4 4779.5 4786.5 4788.1 4814.2 4773.3 4797.2 4798.0 4803.6 4816.1 4198.6 4206.9 4218.6 4223.6 4226.3 6070.3 6103.9 6108.3 6110.9 6111.7 5483.8 5499.9 5513.4 5520.1 5521.9 6526.5 6541.7 6543.7 6549.1 6563.5 4589.3 4596.8 4608.7 4608.8 4611.7 6497.5 6889.3 6928.6 6983.6 7001.1 4546.6 4557.1 4570.4 4578.4 4588.2 4646.2 4653.4 4653.7 4655.5 4668.4 5272.5 5382.2 5418.7 5430.7 5448.9 6570.4 6595.7 6603.2 6609.4 6618.6 1625.4 1629.0 1631.8 1634.2 1648.3 1060.8 1081.6 1094.4 1096.2 1104.1 5767.8 5768.0 5776.4 5801.4 5804.6 6766.6 6778.6 6802.7 6817.2 6820.4 5712.1 5769.4 5805.9 5837.2 5844.4 3596.6 3600.2 3621.2 3627.4 3640.4 6484.2 6520.9 6524.5 6543.8 6622.3 6966.9 6967.4 6977.0 6983.0 7014.9 6536.9 6579.5 6581.9 6589.7 6629.4 6769.7 6800.8 6801.9 6807.9 6824.6 6703.0 6713.8 6730.2 6753.6 6758.4 6135.2 6471.8 6571.9 6581.3 6634.9 4593.1 4603.6 4635.7 4642.6 4662.6 6726.0 6736.6 6768.6 6772.0 6797.3 6382.9 6532.6 6539.3 6551.3 6593.7 6550.8 6571.5 6578.4 6579.8 6581.8 6431.5 6460.5 6479.7 6531.9 6556.5 6626.4 6627.7 6631.5 6639.8 6667.8 6354.6 6438.8 6445.6 6454.5 6454.6 18:35:34 INFO - PID 6222 | 18:35:34 INFO - PID 6222 | [#5] yui.html Cycles:180 Average:19661.18 Median:28043.87 stddev:25538.14 (91.1%) stddev-sans-first:25570.60 18:35:34 INFO - PID 6222 | Values: 821.9 851.9 858.7 862.8 873.7 16646.7 17019.2 17454.7 17509.3 18842.8 17410.9 17449.6 17568.6 17623.4 17696.7 890.1 920.0 925.7 926.7 927.3 956.3 987.2 997.1 1001.8 1002.7 1037.3 1039.6 1041.9 1045.7 1045.9 1031.2 1032.5 1033.2 1033.3 1037.4 758.5 765.0 765.6 766.8 767.1 15886.2 16035.8 16061.4 16084.8 16093.8 18608.9 18703.3 19065.0 19117.6 19139.6 13986.7 14149.9 14218.2 14246.8 14299.9 3450.8 3459.7 3501.9 3504.8 3509.2 89514.6 91475.5 92306.0 92491.5 93457.0 14599.9 14703.3 14859.1 14864.9 14924.6 27154.3 27366.5 27881.0 27912.2 28175.6 8103.1 8196.5 8402.2 8452.3 8478.7 3990.1 4088.9 4111.9 4112.6 4113.1 4226.3 4319.2 4329.8 4358.1 4383.2 814.7 839.9 843.2 843.6 851.3 657.5 679.5 688.2 691.7 698.8 10114.2 10239.2 10649.2 10764.4 11012.0 656.1 672.4 679.3 680.8 685.7 4836.6 4887.2 4902.1 5015.2 5113.2 90838.0 93059.0 95435.0 96939.0 97310.0 77655.0 80926.0 81166.6 81209.6 85989.6 68298.3 69448.7 69754.0 70172.7 71132.3 10234.7 10319.8 10609.5 10638.1 11124.1 28549.3 28703.7 28866.0 28869.0 30138.1 54695.7 55100.7 56065.7 56851.9 56873.4 16487.3 16719.3 16736.2 16804.7 16990.5 17593.5 18206.2 18452.2 18459.5 18802.2 14612.4 14616.9 14747.9 14947.3 15153.2 15125.7 15130.8 15266.7 15389.8 17451.7 14524.2 14545.2 14673.8 14846.2 14852.3 10320.6 10895.5 10958.6 11163.7 11290.8 12734.6 13285.3 13310.5 13355.2 13369.7 18:35:34 INFO - PID 6222 | -------- Summary: end -------- 18:35:34 INFO - PID 6222 | 18:35:35 INFO - TEST-INFO | 6222: exit 0 18:35:35 INFO - TEST-OK | dromaeo_css | took 1143968ms 18:35:35 INFO - TEST-START | kraken 18:35:35 INFO - operating with platform_type : linux_ 18:35:35 INFO - Initialising browser for kraken test... 18:35:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41227/getInfo.html -profile /tmp/tmpHUAZ1Z/profile 18:35:35 INFO - TEST-INFO | started process 6648 (/builds/slave/test/build/application/firefox/firefox http://localhost:41227/getInfo.html) 18:36:10 INFO - TEST-INFO | 6648: killed by SIGTERM 18:36:11 INFO - Browser initialized. 18:36:11 INFO - Running cycle 1/1 for kraken test... 18:36:11 INFO - TEST-INFO | started process 6877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHUAZ1Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:36:22 INFO - PID 6877 | RSS: Main: 191700992 18:36:22 INFO - PID 6877 | 18:36:25 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41227/tests/kraken/driver.html?testName=audio-beat-detection) 18:36:26 INFO - PID 6877 | RSS: Main: 196300800 18:36:26 INFO - PID 6877 | 18:36:28 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41227/tests/kraken/driver.html?testName=audio-dft) 18:36:29 INFO - PID 6877 | RSS: Main: 194420736 18:36:29 INFO - PID 6877 | 18:36:32 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41227/tests/kraken/driver.html?testName=audio-fft) 18:36:33 INFO - PID 6877 | RSS: Main: 189595648 18:36:33 INFO - PID 6877 | 18:36:35 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41227/tests/kraken/driver.html?testName=audio-oscillator) 18:36:35 INFO - PID 6877 | RSS: Main: 190390272 18:36:35 INFO - PID 6877 | 18:36:37 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:36:38 INFO - PID 6877 | RSS: Main: 190349312 18:36:38 INFO - PID 6877 | 18:36:41 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41227/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:42 INFO - PID 6877 | RSS: Main: 191561728 18:36:42 INFO - PID 6877 | 18:36:45 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41227/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:46 INFO - PID 6877 | RSS: Main: 189931520 18:36:46 INFO - PID 6877 | 18:36:49 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41227/tests/kraken/driver.html?testName=json-parse-financial) 18:36:49 INFO - PID 6877 | RSS: Main: 191090688 18:36:49 INFO - PID 6877 | 18:36:51 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:52 INFO - PID 6877 | RSS: Main: 191356928 18:36:52 INFO - PID 6877 | 18:36:53 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:54 INFO - PID 6877 | RSS: Main: 190820352 18:36:54 INFO - PID 6877 | 18:36:56 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:57 INFO - PID 6877 | RSS: Main: 191115264 18:36:57 INFO - PID 6877 | 18:37:00 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:37:00 INFO - PID 6877 | RSS: Main: 191438848 18:37:00 INFO - PID 6877 | 18:37:04 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:37:04 INFO - PID 6877 | RSS: Main: 191184896 18:37:04 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | Cycle 1(1): loaded http://localhost:41227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41227/tests/kraken/driver.html?testName=ai-astar) 18:37:06 INFO - PID 6877 | RSS: Main: 192577536 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | __start_tp_report 18:37:06 INFO - PID 6877 | _x_x_mozilla_page_load 18:37:06 INFO - PID 6877 | _x_x_mozilla_page_load_details 18:37:06 INFO - PID 6877 | |i|pagename|runs| 18:37:06 INFO - PID 6877 | |0;ai-astar;102;112;111;109;109;109;111;109;109;110 18:37:06 INFO - PID 6877 | |1;audio-beat-detection;149;124;124;183;121;130;123;126;145;126 18:37:06 INFO - PID 6877 | |2;audio-dft;163;164;163;163;165;166;168;171;166;165 18:37:06 INFO - PID 6877 | |3;audio-fft;74;79;78;101;78;75;74;74;106;73 18:37:06 INFO - PID 6877 | |4;audio-oscillator;81;81;99;82;82;81;94;82;82;118 18:37:06 INFO - PID 6877 | |5;imaging-gaussian-blur;114;115;117;114;114;114;114;114;114;114 18:37:06 INFO - PID 6877 | |6;imaging-darkroom;151;150;150;149;152;151;150;150;151;151 18:37:06 INFO - PID 6877 | |7;imaging-desaturate;94;94;96;94;94;94;94;94;94;94 18:37:06 INFO - PID 6877 | |8;json-parse-financial;57;55;57;53;54;58;60;61;55;55 18:37:06 INFO - PID 6877 | |9;json-stringify-tinderbox;56;59;55;61;56;56;55;55;55;55 18:37:06 INFO - PID 6877 | |10;stanford-crypto-aes;100;86;87;89;89;89;90;89;108;97 18:37:06 INFO - PID 6877 | |11;stanford-crypto-ccm;139;129;133;129;134;133;136;130;145;148 18:37:06 INFO - PID 6877 | |12;stanford-crypto-pbkdf2;185;194;193;191;184;194;193;190;193;192 18:37:06 INFO - PID 6877 | |13;stanford-crypto-sha256-iterative;74;72;72;73;74;78;71;71;72;70 18:37:06 INFO - PID 6877 | __end_tp_report 18:37:06 INFO - PID 6877 | __start_cc_report 18:37:06 INFO - PID 6877 | _x_x_mozilla_cycle_collect,993 18:37:06 INFO - PID 6877 | __end_cc_report 18:37:06 INFO - PID 6877 | __startTimestamp1496713026688__endTimestamp 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | ------- Summary: start ------- 18:37:06 INFO - PID 6877 | Number of tests: 14 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:2.73 (2.5%) stddev-sans-first:1.17 18:37:06 INFO - PID 6877 | Values: 102.0 112.0 111.0 109.0 109.0 109.0 111.0 109.0 109.0 110.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:128.00 stddev:19.35 (15.1%) stddev-sans-first:19.86 18:37:06 INFO - PID 6877 | Values: 149.0 124.0 124.0 183.0 121.0 130.0 123.0 126.0 145.0 126.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#2] audio-dft Cycles:10 Average:165.40 Median:165.50 stddev:2.55 (1.5%) stddev-sans-first:2.55 18:37:06 INFO - PID 6877 | Values: 163.0 164.0 163.0 163.0 165.0 166.0 168.0 171.0 166.0 165.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#3] audio-fft Cycles:10 Average:81.20 Median:74.50 stddev:11.99 (16.1%) stddev-sans-first:12.43 18:37:06 INFO - PID 6877 | Values: 74.0 79.0 78.0 101.0 78.0 75.0 74.0 74.0 106.0 73.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:82.00 stddev:12.24 (14.9%) stddev-sans-first:12.70 18:37:06 INFO - PID 6877 | Values: 81.0 81.0 99.0 82.0 82.0 81.0 94.0 82.0 82.0 118.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.97 (0.8%) stddev-sans-first:1.01 18:37:06 INFO - PID 6877 | Values: 114.0 115.0 117.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:151.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 18:37:06 INFO - PID 6877 | Values: 151.0 150.0 150.0 149.0 152.0 151.0 150.0 150.0 151.0 151.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 18:37:06 INFO - PID 6877 | Values: 94.0 94.0 96.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:2.59 (4.5%) stddev-sans-first:2.74 18:37:06 INFO - PID 6877 | Values: 57.0 55.0 57.0 53.0 54.0 58.0 60.0 61.0 55.0 55.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#9] json-stringify-tinderbox Cycles:10 Average:56.30 Median:56.00 stddev:2.06 (3.7%) stddev-sans-first:2.18 18:37:06 INFO - PID 6877 | Values: 56.0 59.0 55.0 61.0 56.0 56.0 55.0 55.0 55.0 55.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:89.00 stddev:7.03 (7.9%) stddev-sans-first:6.89 18:37:06 INFO - PID 6877 | Values: 100.0 86.0 87.0 89.0 89.0 89.0 90.0 89.0 108.0 97.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:135.00 stddev:6.57 (4.9%) stddev-sans-first:6.85 18:37:06 INFO - PID 6877 | Values: 139.0 129.0 133.0 129.0 134.0 133.0 136.0 130.0 145.0 148.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.90 Median:193.00 stddev:3.60 (1.9%) stddev-sans-first:3.13 18:37:06 INFO - PID 6877 | Values: 185.0 194.0 193.0 191.0 184.0 194.0 193.0 190.0 193.0 192.0 18:37:06 INFO - PID 6877 | 18:37:06 INFO - PID 6877 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:72.50 stddev:2.26 (3.1%) stddev-sans-first:2.35 18:37:06 INFO - PID 6877 | Values: 74.0 72.0 72.0 73.0 74.0 78.0 71.0 71.0 72.0 70.0 18:37:06 INFO - PID 6877 | -------- Summary: end -------- 18:37:06 INFO - PID 6877 | 18:37:07 INFO - TEST-INFO | 6877: exit 0 18:37:07 INFO - TEST-OK | kraken | took 92088ms 18:37:07 INFO - SUITE-END | took 1236s 18:37:07 INFO - Completed test suite (00:20:36) 18:37:07 INFO - javascript benchmark 18:37:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7719.233331422315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.579801726767, 3121.282105115825, 3144.434001879662, 3160.478521044029, 3165.446046941786, 5884.293884733827, 6037.064255040465, 6039.72821223045, 6065.666388348639, 6079.05774604937, 5948.999999999989, 5957.40425957404, 6012.428819262163, 6039.818805435833, 6111.7249723762425, 5505.146702261136, 5601.579881508883, 5605.943940560583, 5621.184928185411, 5642.463965923252, 5639.915401268984, 5666.121751128581, 5680.431956804307, 5682.232898558704, 5730.226419433386, 5482.205080263339, 5506.421825708323, 5537.335519737658, 5571.777128914839, 5600.467955544217, 5580.135079062752, 5616.550675945915, 5618.0, 5622.91565626516, 5662.348829884575, 5501.972490137564, 5547.1124620061, 5559.444055594448, 5578.107502799573, 5585.0, 6028.517718582503, 6045.486133678643, 6056.636601803906, 6068.726907289182, 6069.57512974092, 6345.777897773556, 6398.552101352907, 6422.4862011039, 6473.74105035798, 6528.249251336109, 6618.635975021371, 6620.23867255267, 6633.905405608069, 6636.336366363325, 6647.933520664828, 6120.357362476965, 6128.050152226412, 6130.846728831771, 6134.17188679527, 6142.78500252489, 6522.804315870531, 6620.073189753439, 6629.767958121539, 6702.932970670328, 6720.462363010947, 6155.815325540241, 6234.1583886175695, 6356.713947872357, 6413.0, 6437.452816510649, 6293.056041593798, 6378.489720822416, 6391.744330226832, 6400.0719895614875, 6401.3278605746655, 6412.487001039905, 6523.7716679915975, 6527.771527996592, 6548.934510654833, 6559.573627714183, 6273.811785646438, 6380.298167201604, 6429.421352078335, 6556.98366753154, 6565.934340656628, 5342.91985620216, 5352.464753524617, 5367.114426119724, 5370.2213179089595, 5388.191771234267, 4534.478534968455, 4548.226801443762, 4551.931721024187, 4567.132244873464, 4636.165490211794, 5580.609357345028, 5643.5767317451355, 5646.915296270559, 5651.6043876928225, 5654.462826031603, 6481.157449531531, 6493.902591461132, 6549.967250163719, 6564.343565643493, 6566.770163044366, 5660.858478537987, 5682.7442765075675, 5686.402927692616, 5697.4302569742695, 5703.230063941315, 5773.364929857712, 5824.330202026821, 5826.329972053183, 5830.47525722687, 5841.561882858718, 6154.59995100326, 6203.31763506023, 6247.0, 6318.873622527615, 6331.715072821642, 6599.703013364506, 6609.867802644017, 6615.272320044887, 6616.768413105518, 6626.304238055128, 6387.457066149429, 6389.329120442287, 6392.137061496825, 6404.391582799627, 6406.743730250738, 6236.314005459305, 6298.590591611531, 6320.905186422299, 6380.298167201511, 6441.967790161019, 6532.934670653232, 6565.474762018932, 6582.275949645439, 6621.039949207434, 6623.437007854193, 6159.692015399301, 6373.490120790419, 6379.585326953733, 6380.042993551005, 6395.1046853441485, 6268.153799237227, 6275.3724627536885, 6378.394052564999, 6397.200349956255, 6399.64801935898, 6260.7495700171485, 6273.874522509433, 6286.057091436138, 6299.622022678654, 6314.1160237565855, 6316.715747791269, 6394.648294343856, 6409.070684750778, 6412.807615771534, 6422.903656445251, 6103.05402662588, 6143.665407145256, 6153.230846144232, 6189.133521306931, 6221.035739460391], "value": 5950.811581130774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.7065173930428, 773.7562667759655, 800.4076983032555, 1149.2357582207833, 1171.0982543441544, 36255.199168133106, 36952.07619809503, 37024.25951480969, 37102.62897371022, 37105.25789484216, 39053.21893562127, 39172.41241381374, 39389.0, 39568.208635827265, 39587.0, 7402.962985185067, 7444.516106453077, 7503.0, 7576.772696819105, 7580.772576822704, 7583.62081895903, 7696.6536505857375, 7703.268189522015, 7709.267619576131, 7711.383089352838, 7644.235576442366, 7644.885326720104, 7664.080310362764, 7693.384529237647, 7721.613919304041, 7635.312821846032, 7637.961810190914, 7646.0, 7650.540967541965, 7664.65509052094, 6878.759243426456, 6905.930940690579, 6918.235849858254, 6982.546134501292, 6989.685464154125, 35512.467312990324, 35739.64260357389, 35753.64246357529, 35793.64206357929, 35830.0, 3553.466979953002, 3556.697680697144, 3607.0441333046765, 3614.0, 3616.33097876891, 28299.575506367422, 28325.43349133006, 28617.14148575525, 28700.569491457645, 28726.856365718246, 3666.0651533858695, 3668.1746607013474, 3692.2061756722164, 3704.9629503704887, 3709.870154544578, 142800.28599856934, 144236.0, 153601.0, 167201.0, 167745.0, 43992.12015759731, 44470.0, 44474.0, 44508.77745611316, 44612.0, 46313.76843115763, 46438.0, 46510.651275103795, 46611.766941165755, 46743.29885051727, 14981.400743970336, 15037.548873533811, 15108.471203507825, 15165.24173791328, 15239.923800380928, 12271.263724176399, 12425.19236249641, 12438.875611243953, 12464.81302780459, 12478.937605311916, 13799.103058301178, 13800.93099534516, 13863.168209907439, 13943.16341019542, 14033.71932561343, 5699.943000570024, 5747.195392645033, 5748.511376532958, 5844.678542680108, 5873.471387575053, 975.8145952269047, 998.7602975285879, 1002.293383164867, 1003.5383723487278, 1008.9545970431349, 21534.353969380943, 22434.439139021328, 22480.43798905008, 22942.770572294397, 23141.652875206884, 1076.4617691154422, 1076.5101878645198, 1084.5065495199665, 1090.3512410115968, 1094.8795632480421, 11423.314601123959, 11911.118978428736, 11963.700907477209, 12342.506299748089, 12888.742225155633, 194299.0, 194482.0, 194726.0, 194874.0, 195657.0, 49042.75478622656, 49242.507574923795, 49549.75225123923, 49614.25578616324, 49758.0, 1429.2639290765264, 1459.1172340733915, 1461.3789139615621, 1477.7487827069208, 1478.4899209772611, 20692.0, 22216.444588884762, 23164.189253376724, 23473.530529388976, 23541.17605883786, 35599.46600800938, 35607.0, 35788.46317305294, 35914.46128308025, 35922.10194745152, 163409.0, 163523.0, 164111.0, 164150.17924910298, 164816.0, 31375.37249255048, 32322.83838580792, 32418.513722294658, 32877.178070547474, 32900.0, 40067.599324007555, 40545.0, 41070.0, 41378.27595516418, 41860.0, 29377.265568360108, 30188.69811301859, 30402.0, 30595.23511912131, 31451.52822707707, 30178.54732179063, 30340.84829575838, 30471.14906559798, 30498.695013049586, 31898.52152217765, 33228.33543329168, 33851.32297354088, 34011.14972125716, 34094.31811363709, 34151.658483414845, 29323.853380733814, 30464.390712185188, 30794.692053079183, 31144.0, 31407.979483854284, 23684.763152368945, 24826.875865620557, 24872.12947546827, 24987.50024999526, 25108.12121575736], "value": 16213.832849510547, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.90526178978854, 414.8195534942302, 427.7412165639788, 430.9353596960457, 440.4868328397415, 14641.267936603153, 15307.081575105502, 20459.18163273469, 21177.682334764973, 21243.468913277175, 20776.8961155194, 21715.565688686216, 21741.565168696616, 21799.67300490494, 21808.345749627537, 5954.285485741717, 6111.0, 6117.0, 6130.264368275791, 6139.508839292868, 5965.105234214859, 6042.214512113441, 6078.908816367736, 6078.939210607904, 6085.178500902388, 5958.7616495340135, 5990.191324171246, 6003.369646187153, 6288.9056664150075, 6623.105880706091, 6650.700718467657, 6682.365175308338, 6707.463402927754, 6709.396154346084, 6736.764213252537, 6574.309697481791, 6599.142111525519, 6609.6695165241545, 6618.437432818217, 6622.238442579117, 26127.869360653265, 26544.0, 26845.03366877134, 26932.326691832666, 26958.73041269582, 1950.980490195094, 1999.9900050474562, 2004.83961283097, 2006.7491563554556, 2007.5583371658213, 17609.647807043915, 18081.09594520282, 18093.819061809347, 18114.275428982826, 18124.90937545317, 2394.892229849661, 2413.9034438622434, 2427.720812106595, 2428.9028438862424, 2483.962740558893, 118813.0, 119623.0, 121338.0, 121416.0, 122391.3880430601, 18868.33960811365, 19769.209231630575, 20093.995300235223, 20113.0, 20573.279935202198, 36909.46358046261, 37090.814545927096, 37281.25437491235, 37355.81322093372, 37362.0, 10568.471576421149, 10683.30558513694, 10707.571697132184, 10714.624988125379, 10916.471693962852, 8083.19168083187, 8566.100559441293, 8699.391042627081, 8797.252233560092, 8831.337649676172, 7616.771496855104, 7764.961175194087, 7842.176571460029, 7848.568328741974, 7938.841223175504, 2095.6751703485984, 2114.365690292906, 2136.1775716349316, 2165.588538177741, 2245.719285089364, 899.8020435504178, 907.9773005674779, 923.8706581078654, 956.31623389277, 976.3556053004983, 17966.910165449088, 17993.370232041816, 18333.908330458264, 19040.52398690044, 19646.607067858848, 997.2520609542843, 998.3311181508407, 1016.4866742295094, 1020.6008327784896, 1021.9662870886882, 10040.246981476419, 10073.69778906634, 10382.273240873066, 10788.622398216025, 10967.122630189566, 403701.0, 409914.0, 410067.0, 421606.0, 424703.0, 97329.0, 99073.50463247638, 99928.50035749775, 100230.4988475053, 100592.49703751435, 1143.5940241214416, 1162.0006794157018, 1180.226951346869, 1194.1760185471996, 1196.1567095197865, 15474.0, 15820.841791581937, 16082.195890205678, 17127.91436042862, 17147.914260428617, 43908.0, 45399.54600453953, 48340.758296208296, 49105.263421047996, 49328.260076098166, 59632.40367596268, 63230.36769632245, 66665.33334666591, 66711.33221669497, 67010.32989670041, 10169.389836609827, 10499.107575855833, 10503.632372867218, 10507.31702439339, 10537.315074520131, 17145.657086857944, 17680.55798604994, 17965.73051404204, 18029.0, 18097.90951045289, 13550.59348219555, 13701.657458563615, 13712.451501939811, 13725.176489410267, 14293.499727509487, 12754.48982040708, 13080.0, 13084.0, 13357.0, 13362.0, 13522.86477135216, 14378.496752613608, 14480.565583032105, 14528.0, 14538.0, 9975.755455022561, 10025.298229124182, 10127.544260508148, 10186.134178594903, 10244.487775611338, 12800.61598152057, 12916.41876115596, 13009.674758131123, 13022.674433139247, 14061.086029408056], "value": 10794.029958722977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.4908585651597, 1378.1868697468492, 1379.92410417427, 1390.582825152454, 1397.6226418866902, 10726.678199654014, 10982.341059536433, 11090.889091109077, 11154.330740155596, 11566.53733850645, 11284.774304513905, 11505.07959363251, 11516.481758320875, 11516.481758320875, 11522.539098436073, 2325.465143017103, 2359.1860808021206, 2373.22870067228, 2373.3947843299934, 2378.8096952243864, 2240.2271216430386, 2292.518571100071, 2301.3786277705094, 2305.5849947009533, 2312.491251924574, 2223.7442694090146, 2242.517858660388, 2245.6182448983714, 2252.1666983216232, 2259.378670865509, 2144.6997420361163, 2208.4368486036037, 2209.06114901168, 2213.612617791888, 2217.0466699319286, 1878.7839398469214, 1923.692209246528, 2159.9568008639894, 2166.1876796201423, 2173.6413491773997, 10859.891401085966, 10929.890701092967, 10955.83566246507, 10964.342139471657, 10973.177011724072, 6202.28673702521, 6436.131122298477, 6440.194975628046, 6443.259025212114, 6465.870682586369, 3607.350676878161, 3799.411091280856, 3837.6162383761402, 3892.1048158923604, 3893.085124995652, 1323.4573824731813, 1329.9268540230285, 1340.537514557476, 1349.6355983884393, 1354.945802167912, 15898.920505397398, 15923.52229433119, 15941.0, 16045.679086418206, 16093.999999999884, 5209.29674493946, 5211.5830733541225, 5257.842264732064, 5409.269033909111, 5411.404745477994, 12315.568955086528, 12325.753484930252, 12328.50685972551, 12335.074869384833, 12354.938225308815, 6162.7226774795245, 6309.558330916883, 6312.936870631235, 6321.494280457552, 6326.114343991845, 5775.047117225695, 5799.594028418054, 5848.795292164839, 5872.17789509469, 5896.528277737771, 4187.937180942288, 4208.726432781859, 4275.29457639486, 4277.422547955986, 4321.157374311979, 1203.6569577670498, 1223.1865809236758, 1237.307108019512, 1244.6266120163914, 1248.8688687687843, 836.1554829622004, 849.76631426357, 861.1130535548396, 865.1391865094272, 887.6316328723636, 5760.769569217185, 5897.056470964625, 5969.671668058211, 6002.249718785152, 6124.724387402578, 594.6075590110593, 601.6570554783731, 613.3406587918006, 635.2504045226584, 641.9775307864203, 3779.225258821935, 3917.804109794556, 4217.283061879488, 4273.7008409411665, 4323.718958267703, 13617.591472255848, 14201.28993550339, 14264.144151350953, 14283.14301141914, 14293.0, 13679.589612311647, 13916.0, 14051.929740351234, 14058.297085145701, 14098.365573549216, 8285.0, 8442.746717598358, 8603.913960860436, 8752.56237188138, 8895.332850036402, 6286.528510361833, 6373.681315934092, 6560.671966401756, 6649.534532582673, 6772.830679233059, 12645.810312845131, 12720.74558508843, 12846.743065138458, 12855.485780568673, 13031.283279419724, 12777.55278565283, 12796.296203708886, 12840.293783841978, 12954.935225323814, 13149.60551183466, 9399.906000940176, 9519.238460923249, 9528.380655257386, 9539.523023848918, 9602.279829012712, 10326.48367581633, 10375.118114960313, 10494.0, 10654.414007229676, 10704.0, 8806.0, 8823.955880220557, 8830.072842351461, 8848.292136628925, 8867.512286824287, 7971.720989765562, 8088.231617996281, 8144.918550814416, 8160.347172226327, 8262.42163048581, 7026.929730702627, 7262.96368518154, 7300.853982920205, 7368.705251790083, 7463.141738699902, 5317.867053323698, 5680.091185410314, 5770.13447982806, 5804.158397032322, 5825.70871456434, 5924.259467566554, 5999.460048595647, 6038.184845045863, 6326.778562750282, 6345.7144428499905], "value": 5100.746341672171, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.5199057150277, 401.5001323352425, 402.30803018807643, 402.7717841500375, 403.95960403959606, 6190.226221722285, 6280.528960327976, 6288.811335659926, 6296.016754722265, 6355.936440635593, 5987.88024239516, 6090.8073093686235, 6100.908486372708, 6114.449699527042, 6148.985417406123, 4629.768511574408, 4779.450363208223, 4794.640401969848, 4799.880002999918, 4801.6878902871285, 4818.277258411232, 4826.155422801014, 4829.637777166709, 4842.588379987706, 4845.825983980499, 4768.427788665365, 4779.498152693969, 4786.4974177711365, 4788.1086970646575, 4814.1575224335775, 4773.331733557305, 4797.208460603996, 4797.999999999983, 4803.591694705954, 4816.109019831308, 4198.6011328923705, 4206.894827629303, 4218.578142185787, 4223.598758117974, 4226.260404429243, 6070.332263451061, 6103.938960610381, 6108.267007959073, 6110.8777824443705, 6111.655435804116, 5483.835484935458, 5499.890002199933, 5513.448655134494, 5520.089185284423, 5521.917171242394, 6526.477881769494, 6541.672916354163, 6543.658549997287, 6549.0503876938055, 6563.54055216135, 4589.265717485219, 4596.770161491912, 4608.654350923661, 4608.778673651452, 4611.723296602181, 6497.545171837974, 6889.345512176335, 6928.584284942869, 6983.580985140857, 7001.124859392525, 4546.613537849254, 4557.088582283556, 4570.360149579062, 4578.397560853675, 4588.174128656807, 4646.233371493666, 4653.371794807726, 4653.650976176733, 4655.464621568495, 4668.385704005767, 5272.472752724697, 5382.192671099288, 5418.6748795071635, 5430.701311427831, 5448.91826622601, 6570.408663220333, 6595.70319335631, 6603.240627327918, 6609.438197753245, 6618.569792963442, 1625.390478570536, 1628.9981661278227, 1631.7633943078188, 1634.182908545727, 1648.3159488812248, 1060.8090543702053, 1081.567373050786, 1094.4308959341258, 1096.1997741648481, 1104.089126470665, 5767.769289228384, 5767.971160144172, 5776.393478684761, 5801.361850196475, 5804.593678442468, 6766.560173588701, 6778.627175505395, 6802.727890884408, 6817.181938167451, 6820.420264277592, 5712.143178523172, 5769.365369809318, 5805.88388232233, 5837.211976383134, 5844.444777746107, 3596.6403359663823, 3600.1719604491113, 3621.167131559756, 3627.367684541914, 3640.381135207021, 6484.1894763154605, 6520.9347906520325, 6524.478041756648, 6543.836404089936, 6622.337766223339, 6966.895496567556, 6967.407770339578, 6976.965115174392, 6983.0, 7014.894776578355, 6536.934630653727, 6579.47364210862, 6581.868362632817, 6589.703463344161, 6629.370209830058, 6769.679912417031, 6800.761973330909, 6801.9319806801295, 6807.897881531682, 6824.556403833735, 6703.0, 6713.798586042427, 6730.158730158731, 6753.628550429774, 6758.42553382968, 6135.233095863017, 6471.805845824632, 6571.901421478586, 6581.308962558863, 6634.900476492953, 4593.127305811885, 4603.631709463168, 4635.745034023161, 4642.582167604932, 4662.603678687214, 6726.0, 6736.5958042517605, 6768.56004359715, 6772.002259333607, 6797.320267973163, 6382.893685051847, 6532.5753826001155, 6539.313372095861, 6551.279359270381, 6593.736250550116, 6550.8362290943105, 6571.507136964651, 6578.407943285128, 6579.835504112435, 6581.835454113685, 6431.453326467303, 6460.547761656639, 6479.676016199265, 6531.9020214695865, 6556.508261880283, 6626.403623673892, 6627.701753421204, 6631.469482441296, 6639.834004149935, 6667.799966001027, 6354.586951848115, 6438.839029024124, 6445.613263204223, 6454.548181627241, 6454.612723236621], "value": 4929.362788175006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.9054808697001, 851.8637018077106, 858.6737039924832, 862.8179394229902, 873.7422460374189, 16646.667066658658, 17019.234134463946, 17454.650906981868, 17509.29962801488, 18842.81157188428, 17410.912945435255, 17449.563760905952, 17568.560785980324, 17623.383181588648, 17696.734548981774, 890.1365675294953, 919.9926080941359, 925.676013395313, 926.7080869526111, 927.2535608834885, 956.3162338927665, 987.239825334492, 997.0727223681973, 1001.8096561653299, 1002.6941782756256, 1037.2946396450411, 1039.6205385034445, 1041.8958104189596, 1045.7124290820047, 1045.9320144190642, 1031.1853635627883, 1032.4682788363998, 1033.178622994721, 1033.3076838518211, 1037.3931250218632, 758.4690716498418, 765.0207734100361, 765.5521519910803, 766.7853001159684, 767.0909971683573, 15886.205689715467, 16035.839641603552, 16061.357545698158, 16084.75872861908, 16093.839061609351, 18608.90695546527, 18703.34538291167, 19065.0, 19117.61764764711, 19139.61720765591, 13986.720265594631, 14149.858501414958, 14218.217998010105, 14246.786298205536, 14299.857001429957, 3450.8102054387, 3459.7232221422228, 3501.9124521887156, 3504.8072356020407, 3509.2279698466295, 89514.5524272381, 91475.54262228646, 92306.0, 92491.53754231254, 93457.0, 14599.854001460062, 14703.338349774285, 14859.108453492825, 14864.851351486563, 14924.552263432115, 27154.321141971213, 27366.452670946866, 27881.0, 27912.162635120978, 28175.577366339523, 8103.1491693371345, 8196.54918979462, 8402.201790829862, 8452.28155606768, 8478.660853565912, 3990.1221731219084, 4088.8773336799945, 4111.9177616447505, 4112.629863312316, 4113.115680128787, 4226.281532139544, 4319.157764235943, 4329.783510824446, 4358.084802191512, 4383.232934236496, 814.7026335387541, 839.8572242718752, 843.2495079379357, 843.5697794125041, 851.3317046118767, 657.5298661457081, 679.4937771360368, 688.1501345837886, 691.7475121580587, 698.8287869472011, 10114.190864730805, 10239.18086553074, 10649.201309901788, 10764.3541387517, 11012.0, 656.1240743607337, 672.4351544702473, 679.341039191983, 680.7787469072472, 685.7325642999234, 4836.564709176191, 4887.242477416006, 4902.117618828645, 5015.1975683890405, 5113.15632920564, 90838.0, 93059.0, 95435.0, 96939.0, 97310.0, 77655.0, 80926.0, 81166.59416702879, 81209.59395202986, 85989.57005214934, 68298.31701683019, 69448.65275673589, 69754.0, 70172.73704472186, 71132.2886771136, 10234.692959211236, 10319.793604127724, 10609.51466794634, 10638.095761860019, 11124.05445537106, 28549.28626784264, 28703.712962870104, 28866.0, 28869.0, 30138.09585712432, 54695.72652136714, 55100.72449637726, 56065.719671401384, 56851.86296274134, 56873.43126568847, 16487.34050637961, 16719.331226750794, 16736.246868891172, 16804.747928781322, 16990.49028529146, 17593.472195834147, 18206.18072186781, 18452.169652365945, 18459.538511537317, 18802.247910083443, 14612.41550338017, 14616.853831461549, 14747.92626036863, 14947.25263736787, 15153.166575838435, 15125.697486049998, 15130.848691513384, 15266.694666106838, 15389.769153462483, 17451.650966980844, 14524.201168935388, 14545.237598067386, 14673.779893301822, 14846.18345990981, 14852.257387130385, 10320.64917671155, 10895.509702063267, 10958.616448424267, 11163.665090047312, 11290.830637540608, 12734.61796146117, 13285.269310188032, 13310.534131305745, 13355.198688078746, 13369.66575835612], "value": 8078.775938153501, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 109.0, 109.0, 109.0, 111.0, 109.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 124.0, 183.0, 121.0, 130.0, 123.0, 126.0, 145.0, 126.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 163.0, 165.0, 166.0, 168.0, 171.0, 166.0, 165.0], "value": 165.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 78.0, 101.0, 78.0, 75.0, 74.0, 74.0, 106.0, 73.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 99.0, 82.0, 82.0, 81.0, 94.0, 82.0, 82.0, 118.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 117.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 149.0, 152.0, 151.0, 150.0, 150.0, 151.0, 151.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 57.0, 53.0, 54.0, 58.0, 60.0, 61.0, 55.0, 55.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 55.0, 61.0, 56.0, 56.0, 55.0, 55.0, 55.0, 55.0], "value": 56.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 86.0, 87.0, 89.0, 89.0, 89.0, 90.0, 89.0, 108.0, 97.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 129.0, 133.0, 129.0, 134.0, 133.0, 136.0, 130.0, 145.0, 148.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 194.0, 193.0, 191.0, 184.0, 194.0, 193.0, 190.0, 193.0, 192.0], "value": 190.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 72.0, 73.0, 74.0, 78.0, 71.0, 71.0, 72.0, 70.0], "value": 72.7, "unit": "score"}]}]} 18:37:08 INFO - Return code: 0 18:37:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:37:08 INFO - # TBPL SUCCESS # 18:37:08 INFO - Running post-action listener: _resource_record_post_action 18:37:08 INFO - [mozharness: 2017-06-06 01:37:08.090126Z] Finished run-tests step (success) 18:37:08 INFO - Running post-run listener: _resource_record_post_run 18:37:08 INFO - Total resource usage - Wall time: 1254s; CPU: 13.0%; Read bytes: 659456; Write bytes: 431333376; Read time: 356; Write time: 302904 18:37:08 INFO - TinderboxPrint: CPU usage
12.7% 18:37:08 INFO - TinderboxPrint: I/O read bytes / time
659,456 / 356 18:37:08 INFO - TinderboxPrint: I/O write bytes / time
431,333,376 / 302,904 18:37:08 INFO - TinderboxPrint: CPU idle
8,738.3 (87.3%) 18:37:08 INFO - TinderboxPrint: CPU user
1,213.1 (12.1%) 18:37:08 INFO - TinderboxPrint: Swap in / out
0 / 0 18:37:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 103780352; Read time: 0; Write time: 69412 18:37:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:37:08 INFO - run-tests - Wall time: 1237s; CPU: 13.0%; Read bytes: 659456; Write bytes: 327553024; Read time: 356; Write time: 233492 18:37:08 INFO - Running post-run listener: _upload_blobber_files 18:37:08 INFO - Blob upload gear active. 18:37:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:37:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:37:09 INFO - (blobuploader) - INFO - Open directory for files ... 18:37:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:37:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:37:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:10 INFO - (blobuploader) - INFO - Done attempting. 18:37:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:37:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:37:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:10 INFO - (blobuploader) - INFO - Done attempting. 18:37:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:37:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:13 INFO - (blobuploader) - INFO - Done attempting. 18:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:37:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:25 INFO - (blobuploader) - INFO - Done attempting. 18:37:25 INFO - (blobuploader) - INFO - Iteration through files over. 18:37:25 INFO - Return code: 0 18:37:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:37:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:37:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3567b57b63bdbd1c06062c68aa1058e2a1a4a0dc41cc01583dd0f10dc0183e9a778b260cb0f691947da9cd1ac39a24f7accae601cb3e11f64f3e3204c8bc36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ead7a69fb29c12bf3600d456e255661dd127f5afcb9a65962829177518b72ae141a94a3efd561609f10b88eb1bd833a03d864b90dd03d9eae2b683dddce13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a0726ec3345dae731552760aee74e0a7112e13e1ab408fb44727159a526a22e700d0aa8d03d39f45f2b8856e69215f800848a93916f85012e61b7dbaf4b30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daf3e0f466aedd280693a08d7b41fa2641532fe13d481ba95b07ada5b2b24ad2a6041ea17838c5c63858802947047fb04f85b856a375c897a960699998b35e95"} 18:37:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:37:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:37:25 INFO - Contents: 18:37:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3567b57b63bdbd1c06062c68aa1058e2a1a4a0dc41cc01583dd0f10dc0183e9a778b260cb0f691947da9cd1ac39a24f7accae601cb3e11f64f3e3204c8bc36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ead7a69fb29c12bf3600d456e255661dd127f5afcb9a65962829177518b72ae141a94a3efd561609f10b88eb1bd833a03d864b90dd03d9eae2b683dddce13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a0726ec3345dae731552760aee74e0a7112e13e1ab408fb44727159a526a22e700d0aa8d03d39f45f2b8856e69215f800848a93916f85012e61b7dbaf4b30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daf3e0f466aedd280693a08d7b41fa2641532fe13d481ba95b07ada5b2b24ad2a6041ea17838c5c63858802947047fb04f85b856a375c897a960699998b35e95"} 18:37:25 INFO - Running post-run listener: copy_logs_to_upload_dir 18:37:25 INFO - Copying logs to upload dir... 18:37:25 INFO - mkdir: /builds/slave/test/build/upload/logs 18:37:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.201103 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2017-06-05 18:37:25.760168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 18:37:25.764155) ========= 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=781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3567b57b63bdbd1c06062c68aa1058e2a1a4a0dc41cc01583dd0f10dc0183e9a778b260cb0f691947da9cd1ac39a24f7accae601cb3e11f64f3e3204c8bc36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ead7a69fb29c12bf3600d456e255661dd127f5afcb9a65962829177518b72ae141a94a3efd561609f10b88eb1bd833a03d864b90dd03d9eae2b683dddce13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a0726ec3345dae731552760aee74e0a7112e13e1ab408fb44727159a526a22e700d0aa8d03d39f45f2b8856e69215f800848a93916f85012e61b7dbaf4b30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daf3e0f466aedd280693a08d7b41fa2641532fe13d481ba95b07ada5b2b24ad2a6041ea17838c5c63858802947047fb04f85b856a375c897a960699998b35e95"} build_url:https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/BgQ-WGk2R7y7z_N4RN3DOQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb3567b57b63bdbd1c06062c68aa1058e2a1a4a0dc41cc01583dd0f10dc0183e9a778b260cb0f691947da9cd1ac39a24f7accae601cb3e11f64f3e3204c8bc36", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/763ead7a69fb29c12bf3600d456e255661dd127f5afcb9a65962829177518b72ae141a94a3efd561609f10b88eb1bd833a03d864b90dd03d9eae2b683dddce13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a0726ec3345dae731552760aee74e0a7112e13e1ab408fb44727159a526a22e700d0aa8d03d39f45f2b8856e69215f800848a93916f85012e61b7dbaf4b30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daf3e0f466aedd280693a08d7b41fa2641532fe13d481ba95b07ada5b2b24ad2a6041ea17838c5c63858802947047fb04f85b856a375c897a960699998b35e95"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 18:37:25.788512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:37:25.788883) ========= 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=781b77c4c33858b1f599d705000001c1-1496711169.504327-1466033852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:37:25.872521) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 18:37:25.872854) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 18:37:25.873159) ========= ========= Total master_lag: 0.25 =========