builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-050 starttime: 1498754161.11 results: success (0) revision: f3483af8ecf997453064201c49c48a682c7f3c29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.112510) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.114412) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.114821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.137685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.138061) ========= 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=6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-29 09:36:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.6M=0.001s 2017-06-29 09:36:01 (21.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156275 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.313119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.313514) ========= 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=6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020168 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.365904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:01.369387) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f3483af8ecf997453064201c49c48a682c7f3c29 --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 f3483af8ecf997453064201c49c48a682c7f3c29 --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=6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-29 09:36:01,710 truncating revision to first 12 chars 2017-06-29 09:36:01,710 Setting DEBUG logging. 2017-06-29 09:36:01,710 attempt 1/10 2017-06-29 09:36:01,710 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f3483af8ecf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-29 09:36:02,303 unpacking tar archive at: mozilla-central-f3483af8ecf9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.938651 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:02.320933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:02.324395) ========= script_repo_revision: f3483af8ecf997453064201c49c48a682c7f3c29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:02.324887) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:02.325167) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-29 09:36:02.334572) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-06-29 09:36:02.334959) ========= /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=6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:36:02 INFO - MultiFileLogger online at 20170629 09:36:02 in /builds/slave/test 09:36:02 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 09:36:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:02 INFO - {'append_to_log': False, 09:36:02 INFO - 'base_work_dir': '/builds/slave/test', 09:36:02 INFO - 'blob_upload_branch': 'Firefox', 09:36:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:02 INFO - 'branch': 'Firefox', 09:36:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:02 INFO - 'config_files': ('talos/linux_config.py',), 09:36:02 INFO - 'default_actions': ('clobber', 09:36:02 INFO - 'read-buildbot-config', 09:36:02 INFO - 'download-and-extract', 09:36:02 INFO - 'populate-webroot', 09:36:02 INFO - 'create-virtualenv', 09:36:02 INFO - 'install', 09:36:02 INFO - 'setup-mitmproxy', 09:36:02 INFO - 'run-tests'), 09:36:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:02 INFO - 'download_minidump_stackwalk': True, 09:36:02 INFO - 'download_symbols': 'ondemand', 09:36:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:02 INFO - 'gecko_profile': False, 09:36:02 INFO - 'gecko_profile_interval': 0, 09:36:02 INFO - 'installer_path': 'installer.exe', 09:36:02 INFO - 'log_level': 'info', 09:36:02 INFO - 'log_name': 'talos', 09:36:02 INFO - 'log_to_console': True, 09:36:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:36:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:36:02 INFO - 'opt_config_files': (), 09:36:02 INFO - 'pip_index': False, 09:36:02 INFO - 'suite': 'dromaeojs-e10s', 09:36:02 INFO - 'system_bits': '32', 09:36:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:02 INFO - 'title': 'talos-linux64-ix-050', 09:36:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:02 INFO - 'use_talos_json': True, 09:36:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:02 INFO - 'work_dir': 'build'} 09:36:02 INFO - [mozharness: 2017-06-29 16:36:02.781925Z] Running clobber step. 09:36:02 INFO - Running pre-action listener: _resource_record_pre_action 09:36:02 INFO - Running main action method: clobber 09:36:02 INFO - rmtree: /builds/slave/test/build 09:36:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:04 INFO - Running post-action listener: _resource_record_post_action 09:36:04 INFO - [mozharness: 2017-06-29 16:36:04.318109Z] Finished clobber step (success) 09:36:04 INFO - [mozharness: 2017-06-29 16:36:04.318209Z] Running read-buildbot-config step. 09:36:04 INFO - Running pre-action listener: _resource_record_pre_action 09:36:04 INFO - Running main action method: read_buildbot_config 09:36:04 INFO - Using buildbot properties: 09:36:04 INFO - { 09:36:04 INFO - "project": "", 09:36:04 INFO - "product": "firefox", 09:36:04 INFO - "who": "ffxbld@noreply.mozilla.org", 09:36:04 INFO - "installer_path": "public/build/target.tar.bz2", 09:36:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:36:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:36:04 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:36:04 INFO - "stage_platform": "linux64-stylo", 09:36:04 INFO - "basedir": "/builds/slave/test", 09:36:04 INFO - "buildnumber": 3, 09:36:04 INFO - "platform": "ubuntu64_hw_stylo", 09:36:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:36:04 INFO - "slavebuilddir": "test", 09:36:04 INFO - "taskId": "VtJX0nRAQZuRgySheLXrow", 09:36:04 INFO - "branch": "mozilla-central", 09:36:04 INFO - "script_repo_revision": "production", 09:36:04 INFO - "revision": "f3483af8ecf997453064201c49c48a682c7f3c29", 09:36:04 INFO - "slavename": "talos-linux64-ix-050", 09:36:04 INFO - "repo_path": "mozilla-central" 09:36:04 INFO - } 09:36:04 INFO - Finding installer, test and symbols from parent task. 09:36:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VtJX0nRAQZuRgySheLXrow'}, attempt #1 09:36:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VtJX0nRAQZuRgySheLXrow'}, attempt #1 09:36:05 INFO - Task dependencies: Tp3yqa-MSHWH0LwXKp2Dxg 09:36:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg'}, attempt #1 09:36:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2 09:36:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/test_packages.json 09:36:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.crashreporter-symbols.zip 09:36:05 INFO - Running post-action listener: _resource_record_post_action 09:36:05 INFO - [mozharness: 2017-06-29 16:36:05.680703Z] Finished read-buildbot-config step (success) 09:36:05 INFO - [mozharness: 2017-06-29 16:36:05.680919Z] Running download-and-extract step. 09:36:05 INFO - Running pre-action listener: _resource_record_pre_action 09:36:05 INFO - Running main action method: download_and_extract 09:36:05 INFO - mkdir: /builds/slave/test/build/tests 09:36: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')]} 09:36:05 INFO - trying https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.test_packages.json 09:36:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:36:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:36:06 INFO - Downloaded 989 bytes. 09:36:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:36:06 INFO - Using the following test package requirements: 09:36:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:36:06 INFO - u'common': [u'target.common.tests.zip'], 09:36:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:36:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:36:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:36:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:36:06 INFO - u'mozbase': [u'target.common.tests.zip'], 09:36:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:36:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:36:06 INFO - u'web-platform': [u'target.common.tests.zip', 09:36:06 INFO - u'target.web-platform.tests.tar.gz'], 09:36:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:36:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:36:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.common.tests.zip 09:36:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:36:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.common.tests.zip into memory 09:36:09 INFO - Content-Length response header: 67758017 09:36:09 INFO - Bytes received: 67758017 09:36:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:36:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.common.tests.zip 09:36:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:36:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.common.tests.zip into memory 09:36:21 INFO - Content-Length response header: 67758017 09:36:21 INFO - Bytes received: 67758017 09:36:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.talos.tests.zip 09:36:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:36:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.talos.tests.zip into memory 09:36:34 INFO - Content-Length response header: 14442700 09:36:34 INFO - Bytes received: 14442700 09:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:34 INFO - trying https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2 09:36:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:36:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:36:36 INFO - Downloaded 61855617 bytes. 09:36:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2 09:36:36 INFO - mkdir: /builds/slave/test/properties 09:36:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:36 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:36 INFO - Contents: 09:36:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2 09:36:36 INFO - Running post-action listener: _resource_record_post_action 09:36:36 INFO - Running post-action listener: set_extra_try_arguments 09:36:36 INFO - [mozharness: 2017-06-29 16:36:36.466679Z] Finished download-and-extract step (success) 09:36:36 INFO - [mozharness: 2017-06-29 16:36:36.466830Z] Running populate-webroot step. 09:36:36 INFO - Running pre-action listener: _resource_record_pre_action 09:36:36 INFO - Running main action method: populate_webroot 09:36:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:36 INFO - '--tppagecycles', 09:36:36 INFO - '7'], 09:36:36 INFO - 'tests': ['tsvgm']}}, 09:36:36 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['damp', 'tps']}, 09:36:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'tests': ['damp', 'tps']}, 09:36:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['dromaeo_dom']}, 09:36:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:36:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:36:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:36:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['a11yr', 09:36:36 INFO - 'ts_paint', 09:36:36 INFO - 'tpaint', 09:36:36 INFO - 'sessionrestore', 09:36:36 INFO - 'sessionrestore_no_auto_restore', 09:36:36 INFO - 'tabpaint']}, 09:36:36 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:36 INFO - 'ts_paint', 09:36:36 INFO - 'tpaint', 09:36:36 INFO - 'sessionrestore', 09:36:36 INFO - 'sessionrestore_no_auto_restore', 09:36:36 INFO - 'tabpaint']}, 09:36:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:36 INFO - 'ts_paint', 09:36:36 INFO - 'tpaint', 09:36:36 INFO - 'sessionrestore', 09:36:36 INFO - 'sessionrestore_no_auto_restore', 09:36:36 INFO - 'tabpaint']}, 09:36:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:36 INFO - 'ts_paint', 09:36:36 INFO - 'tpaint', 09:36:36 INFO - 'sessionrestore', 09:36:36 INFO - 'sessionrestore_no_auto_restore', 09:36:36 INFO - 'tabpaint']}, 09:36:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['a11yr', 09:36:36 INFO - 'ts_paint', 09:36:36 INFO - 'tpaint', 09:36:36 INFO - 'sessionrestore', 09:36:36 INFO - 'sessionrestore_no_auto_restore', 09:36:36 INFO - 'tabpaint']}, 09:36:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['a11yr', 09:36:36 INFO - 'ts_paint', 09:36:36 INFO - 'tpaint', 09:36:36 INFO - 'sessionrestore', 09:36:36 INFO - 'sessionrestore_no_auto_restore', 09:36:36 INFO - 'tabpaint']}, 09:36:36 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:36:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:36:36 INFO - 'bloom_basic_ref']}, 09:36:36 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:36:36 INFO - 'talos_options': ['--mitmproxy', 09:36:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:36:36 INFO - '--firstNonBlankPaint'], 09:36:36 INFO - 'tests': ['quantum_pageload_google', 09:36:36 INFO - 'quantum_pageload_youtube', 09:36:36 INFO - 'quantum_pageload_amazon', 09:36:36 INFO - 'quantum_pageload_facebook']}, 09:36:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['tsvgx', 09:36:36 INFO - 'tsvgr_opacity', 09:36:36 INFO - 'tart', 09:36:36 INFO - 'tscrollx', 09:36:36 INFO - 'cart', 09:36:36 INFO - 'tsvg_static']}, 09:36:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:36 INFO - 'tsvgr_opacity', 09:36:36 INFO - 'tart', 09:36:36 INFO - 'tscrollx', 09:36:36 INFO - 'cart', 09:36:36 INFO - 'tsvg_static']}, 09:36:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'talos_options': ['--disable-e10s'], 09:36:36 INFO - 'tests': ['tp5o']}, 09:36:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:36:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'talos_options': ['--disable-e10s', 09:36:36 INFO - '--xperf_path', 09:36:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:36 INFO - 'tests': ['tp5n']}, 09:36:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:36:36 INFO - 'talos_options': ['--xperf_path', 09:36:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:36 INFO - 'tests': ['tp5n']}}, 09:36:36 INFO - 'talos.zip': {'path': '', 09:36:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:36 INFO - Running post-action listener: _resource_record_post_action 09:36:36 INFO - [mozharness: 2017-06-29 16:36:36.484306Z] Finished populate-webroot step (success) 09:36:36 INFO - [mozharness: 2017-06-29 16:36:36.484395Z] Running create-virtualenv step. 09:36:36 INFO - Running pre-action listener: _resource_record_pre_action 09:36:36 INFO - Running main action method: create_virtualenv 09:36:36 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:36 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 09:36:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:36:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:36 INFO - Using real prefix '/usr' 09:36:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:37 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:39 INFO - Installing pip.................done. 09:36:39 INFO - Return code: 0 09:36:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:36:39 INFO - Reading from file tmpfile_stdout 09:36:39 INFO - Output received: 09:36:39 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) 09:36:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36:39 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')]} 09:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:39 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')]} 09:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:39 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:39 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 09:36:39 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 09:36:39 INFO - Using env: {'DISPLAY': ':0', 09:36:39 INFO - 'HOME': '/home/cltbld', 09:36:39 INFO - 'LANG': 'en_US.UTF-8', 09:36:39 INFO - 'LANGUAGE': 'en_US:en', 09:36:39 INFO - 'LOGNAME': 'cltbld', 09:36:39 INFO - 'MAIL': '/var/mail/cltbld', 09:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:39 INFO - 'MOZ_NO_REMOTE': '1', 09:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:39 INFO - 'NO_EM_RESTART': '1', 09:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:39 INFO - 'PWD': '/builds/slave/test', 09:36:39 INFO - 'SHELL': '/bin/bash', 09:36:39 INFO - 'SHLVL': '1', 09:36:39 INFO - 'TERM': 'linux', 09:36:39 INFO - 'TMOUT': '86400', 09:36:39 INFO - 'USER': 'cltbld', 09:36:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:39 INFO - '_': '/tools/buildbot/bin/python'} 09:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:39 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 09:36:39 INFO - Cleaning up... 09:36:39 INFO - Return code: 0 09:36:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36:39 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')]} 09:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:39 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')]} 09:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:39 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:39 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 09:36:39 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 09:36:39 INFO - Using env: {'DISPLAY': ':0', 09:36:39 INFO - 'HOME': '/home/cltbld', 09:36:39 INFO - 'LANG': 'en_US.UTF-8', 09:36:39 INFO - 'LANGUAGE': 'en_US:en', 09:36:39 INFO - 'LOGNAME': 'cltbld', 09:36:39 INFO - 'MAIL': '/var/mail/cltbld', 09:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:39 INFO - 'MOZ_NO_REMOTE': '1', 09:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:39 INFO - 'NO_EM_RESTART': '1', 09:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:39 INFO - 'PWD': '/builds/slave/test', 09:36:39 INFO - 'SHELL': '/bin/bash', 09:36:39 INFO - 'SHLVL': '1', 09:36:39 INFO - 'TERM': 'linux', 09:36:39 INFO - 'TMOUT': '86400', 09:36:39 INFO - 'USER': 'cltbld', 09:36:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:39 INFO - '_': '/tools/buildbot/bin/python'} 09:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:39 INFO - Downloading/unpacking psutil>=3.1.1 09:36:39 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 09:36:39 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 09:36:39 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 09:36:39 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 09:36:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:40 INFO - Installing collected packages: psutil 09:36:40 INFO - Running setup.py install for psutil 09:36:40 INFO - building 'psutil._psutil_linux' extension 09:36:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:36:41 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 09:36:41 INFO - building 'psutil._psutil_posix' extension 09:36:41 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 09:36:41 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 09:36:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:41 INFO - Successfully installed psutil 09:36:41 INFO - Cleaning up... 09:36:41 INFO - Return code: 0 09:36:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:41 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')]} 09:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:41 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')]} 09:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:41 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:41 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 09:36:41 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 09:36:41 INFO - Using env: {'DISPLAY': ':0', 09:36:41 INFO - 'HOME': '/home/cltbld', 09:36:41 INFO - 'LANG': 'en_US.UTF-8', 09:36:41 INFO - 'LANGUAGE': 'en_US:en', 09:36:41 INFO - 'LOGNAME': 'cltbld', 09:36:41 INFO - 'MAIL': '/var/mail/cltbld', 09:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:41 INFO - 'MOZ_NO_REMOTE': '1', 09:36:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:41 INFO - 'NO_EM_RESTART': '1', 09:36:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:41 INFO - 'PWD': '/builds/slave/test', 09:36:41 INFO - 'SHELL': '/bin/bash', 09:36:41 INFO - 'SHLVL': '1', 09:36:41 INFO - 'TERM': 'linux', 09:36:41 INFO - 'TMOUT': '86400', 09:36:41 INFO - 'USER': 'cltbld', 09:36:41 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:41 INFO - '_': '/tools/buildbot/bin/python'} 09:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:42 INFO - Installing collected packages: mozsystemmonitor 09:36:42 INFO - Running setup.py install for mozsystemmonitor 09:36:42 INFO - Successfully installed mozsystemmonitor 09:36:42 INFO - Cleaning up... 09:36:42 INFO - Return code: 0 09:36:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:36:42 INFO - Using env: {'DISPLAY': ':0', 09:36:42 INFO - 'HOME': '/home/cltbld', 09:36:42 INFO - 'LANG': 'en_US.UTF-8', 09:36:42 INFO - 'LANGUAGE': 'en_US:en', 09:36:42 INFO - 'LOGNAME': 'cltbld', 09:36:42 INFO - 'MAIL': '/var/mail/cltbld', 09:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:42 INFO - 'MOZ_NO_REMOTE': '1', 09:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:42 INFO - 'NO_EM_RESTART': '1', 09:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:42 INFO - 'PWD': '/builds/slave/test', 09:36:42 INFO - 'SHELL': '/bin/bash', 09:36:42 INFO - 'SHLVL': '1', 09:36:42 INFO - 'TERM': 'linux', 09:36:42 INFO - 'TMOUT': '86400', 09:36:42 INFO - 'USER': 'cltbld', 09:36:42 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:42 INFO - '_': '/tools/buildbot/bin/python'} 09:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:43 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 09:36:43 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 09:36:43 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 09:36:43 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 09:36:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:44 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:44 INFO - warning: no files found matching '*.txt' 09:36:44 INFO - no previously-included directories found matching 'build' 09:36:44 INFO - no previously-included directories found matching 'dist' 09:36:44 INFO - no previously-included directories found matching '.git*' 09:36:44 INFO - Installing collected packages: jsonschema, functools32 09:36:44 INFO - Running setup.py install for functools32 09:36:44 INFO - warning: no files found matching '*.txt' 09:36:44 INFO - no previously-included directories found matching 'build' 09:36:44 INFO - no previously-included directories found matching 'dist' 09:36:44 INFO - no previously-included directories found matching '.git*' 09:36:44 INFO - Successfully installed jsonschema functools32 09:36:44 INFO - Cleaning up... 09:36:44 INFO - Return code: 0 09:36:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:36:44 INFO - Using env: {'DISPLAY': ':0', 09:36:44 INFO - 'HOME': '/home/cltbld', 09:36:44 INFO - 'LANG': 'en_US.UTF-8', 09:36:44 INFO - 'LANGUAGE': 'en_US:en', 09:36:44 INFO - 'LOGNAME': 'cltbld', 09:36:44 INFO - 'MAIL': '/var/mail/cltbld', 09:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:44 INFO - 'MOZ_NO_REMOTE': '1', 09:36:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:44 INFO - 'NO_EM_RESTART': '1', 09:36:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:44 INFO - 'PWD': '/builds/slave/test', 09:36:44 INFO - 'SHELL': '/bin/bash', 09:36:44 INFO - 'SHLVL': '1', 09:36:44 INFO - 'TERM': 'linux', 09:36:44 INFO - 'TMOUT': '86400', 09:36:44 INFO - 'USER': 'cltbld', 09:36:44 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:44 INFO - '_': '/tools/buildbot/bin/python'} 09:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:36:44 INFO - Cleaning up... 09:36:44 INFO - Return code: 0 09:36:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:44 INFO - Using env: {'DISPLAY': ':0', 09:36:44 INFO - 'HOME': '/home/cltbld', 09:36:44 INFO - 'LANG': 'en_US.UTF-8', 09:36:44 INFO - 'LANGUAGE': 'en_US:en', 09:36:44 INFO - 'LOGNAME': 'cltbld', 09:36:44 INFO - 'MAIL': '/var/mail/cltbld', 09:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:44 INFO - 'MOZ_NO_REMOTE': '1', 09:36:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:44 INFO - 'NO_EM_RESTART': '1', 09:36:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:44 INFO - 'PWD': '/builds/slave/test', 09:36:44 INFO - 'SHELL': '/bin/bash', 09:36:44 INFO - 'SHLVL': '1', 09:36:44 INFO - 'TERM': 'linux', 09:36:44 INFO - 'TMOUT': '86400', 09:36:44 INFO - 'USER': 'cltbld', 09:36:44 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:44 INFO - '_': '/tools/buildbot/bin/python'} 09:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:44 INFO - Downloading/unpacking blobuploader==1.2.4 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:46 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:46 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 09:36:46 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 09:36:46 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 09:36:46 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 09:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:46 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 09:36:46 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 09:36:46 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 09:36:46 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 09:36:46 INFO - Downloading docopt-0.6.1.tar.gz 09:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:46 INFO - Running setup.py install for blobuploader 09:36:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:46 INFO - Running setup.py install for requests 09:36:47 INFO - Running setup.py install for docopt 09:36:47 INFO - Successfully installed blobuploader requests docopt 09:36:47 INFO - Cleaning up... 09:36:47 INFO - Return code: 0 09:36:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:47 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')]} 09:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:47 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')]} 09:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:47 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:47 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 09:36:47 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 09:36:47 INFO - Using env: {'DISPLAY': ':0', 09:36:47 INFO - 'HOME': '/home/cltbld', 09:36:47 INFO - 'LANG': 'en_US.UTF-8', 09:36:47 INFO - 'LANGUAGE': 'en_US:en', 09:36:47 INFO - 'LOGNAME': 'cltbld', 09:36:47 INFO - 'MAIL': '/var/mail/cltbld', 09:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:47 INFO - 'MOZ_NO_REMOTE': '1', 09:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:47 INFO - 'NO_EM_RESTART': '1', 09:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:47 INFO - 'PWD': '/builds/slave/test', 09:36:47 INFO - 'SHELL': '/bin/bash', 09:36:47 INFO - 'SHLVL': '1', 09:36:47 INFO - 'TERM': 'linux', 09:36:47 INFO - 'TMOUT': '86400', 09:36:47 INFO - 'USER': 'cltbld', 09:36:47 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:47 INFO - '_': '/tools/buildbot/bin/python'} 09:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:47 INFO - Running setup.py (path:/tmp/pip-f2XV9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:47 INFO - Running setup.py (path:/tmp/pip-luXWWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:47 INFO - Running setup.py (path:/tmp/pip-UnV9pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:47 INFO - Running setup.py (path:/tmp/pip-50Da4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:47 INFO - Running setup.py (path:/tmp/pip-30CY0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:47 INFO - Running setup.py (path:/tmp/pip-E22FOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:47 INFO - Running setup.py (path:/tmp/pip-YDkwDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:47 INFO - Running setup.py (path:/tmp/pip-_zXsgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:48 INFO - Running setup.py (path:/tmp/pip-7PQo2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:48 INFO - Running setup.py (path:/tmp/pip-WBku_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:48 INFO - Running setup.py (path:/tmp/pip-fKMJk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:48 INFO - Running setup.py (path:/tmp/pip-dtCuvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:48 INFO - Running setup.py (path:/tmp/pip-eKtt65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:48 INFO - Running setup.py (path:/tmp/pip-ASKM1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:48 INFO - Running setup.py (path:/tmp/pip-7Ymc4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:48 INFO - Running setup.py (path:/tmp/pip-2Pgj7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:48 INFO - Running setup.py (path:/tmp/pip-MCxYHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:48 INFO - Running setup.py install for manifestparser 09:36:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:48 INFO - Running setup.py install for mozcrash 09:36:48 INFO - Running setup.py install for mozdebug 09:36:49 INFO - Running setup.py install for mozdevice 09:36:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Running setup.py install for mozfile 09:36:49 INFO - Running setup.py install for mozhttpd 09:36:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Running setup.py install for mozinfo 09:36:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Running setup.py install for mozInstall 09:36:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Running setup.py install for mozleak 09:36:49 INFO - Running setup.py install for mozlog 09:36:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Running setup.py install for moznetwork 09:36:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:49 INFO - Running setup.py install for mozprocess 09:36:49 INFO - Running setup.py install for mozprofile 09:36:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Running setup.py install for mozrunner 09:36:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Running setup.py install for mozscreenshot 09:36:50 INFO - Running setup.py install for moztest 09:36:50 INFO - Running setup.py install for mozversion 09:36:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:50 INFO - Cleaning up... 09:36:50 INFO - Return code: 0 09:36:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:50 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')]} 09:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:50 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')]} 09:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:50 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:50 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 09:36:50 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 09:36:50 INFO - Using env: {'DISPLAY': ':0', 09:36:50 INFO - 'HOME': '/home/cltbld', 09:36:50 INFO - 'LANG': 'en_US.UTF-8', 09:36:50 INFO - 'LANGUAGE': 'en_US:en', 09:36:50 INFO - 'LOGNAME': 'cltbld', 09:36:50 INFO - 'MAIL': '/var/mail/cltbld', 09:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:50 INFO - 'MOZ_NO_REMOTE': '1', 09:36:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:50 INFO - 'NO_EM_RESTART': '1', 09:36:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:50 INFO - 'PWD': '/builds/slave/test', 09:36:50 INFO - 'SHELL': '/bin/bash', 09:36:50 INFO - 'SHLVL': '1', 09:36:50 INFO - 'TERM': 'linux', 09:36:50 INFO - 'TMOUT': '86400', 09:36:50 INFO - 'USER': 'cltbld', 09:36:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:50 INFO - '_': '/tools/buildbot/bin/python'} 09:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:50 INFO - Running setup.py (path:/tmp/pip-EhmWNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:50 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)) 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:50 INFO - Running setup.py (path:/tmp/pip-ztYuXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:50 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)) 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:50 INFO - Running setup.py (path:/tmp/pip-Y_bmT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:50 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)) 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:50 INFO - Running setup.py (path:/tmp/pip-0NFRot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:50 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)) 09:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:50 INFO - Running setup.py (path:/tmp/pip-F64Mln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:51 INFO - Running setup.py (path:/tmp/pip-uZsTKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:51 INFO - Running setup.py (path:/tmp/pip-o6e5qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:51 INFO - Running setup.py (path:/tmp/pip-YTtiph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:51 INFO - Running setup.py (path:/tmp/pip-FwGHyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:51 INFO - Running setup.py (path:/tmp/pip-HsE3wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:51 INFO - Running setup.py (path:/tmp/pip-2M96Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:51 INFO - Running setup.py (path:/tmp/pip-VmJ88o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:51 INFO - Running setup.py (path:/tmp/pip-nplac9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:51 INFO - Running setup.py (path:/tmp/pip-3Tzbnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:51 INFO - Running setup.py (path:/tmp/pip-L_uOgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:51 INFO - Running setup.py (path:/tmp/pip-38gHLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:51 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)) 09:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:51 INFO - Running setup.py (path:/tmp/pip-gY5Vu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:51 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)) 09:36:51 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)) 09:36:51 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)) 09:36:51 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)) 09:36:51 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)) 09:36:51 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)) 09:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:36:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:51 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 09:36:51 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 09:36:51 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 09:36:51 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 09:36:53 INFO - Downloading blessings-1.6.tar.gz 09:36:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:53 INFO - Installing collected packages: blessings 09:36:53 INFO - Running setup.py install for blessings 09:36:53 INFO - Successfully installed blessings 09:36:53 INFO - Cleaning up... 09:36:53 INFO - Return code: 0 09:36:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:53 INFO - Reading from file tmpfile_stdout 09:36:53 INFO - Current package versions: 09:36:53 INFO - argparse == 1.2.1 09:36:53 INFO - blessings == 1.6 09:36:53 INFO - blobuploader == 1.2.4 09:36:53 INFO - docopt == 0.6.1 09:36:53 INFO - functools32 == 3.2.3-2 09:36:53 INFO - jsonschema == 2.5.1 09:36:53 INFO - manifestparser == 1.1 09:36:53 INFO - mozInstall == 1.13 09:36:53 INFO - mozcrash == 1.0 09:36:53 INFO - mozdebug == 0.1 09:36:53 INFO - mozdevice == 0.51 09:36:53 INFO - mozfile == 1.2 09:36:53 INFO - mozhttpd == 0.7 09:36:53 INFO - mozinfo == 0.10 09:36:53 INFO - mozleak == 0.1 09:36:53 INFO - mozlog == 3.5 09:36:53 INFO - moznetwork == 0.27 09:36:53 INFO - mozprocess == 0.25 09:36:53 INFO - mozprofile == 0.28 09:36:53 INFO - mozrunner == 6.13 09:36:53 INFO - mozscreenshot == 0.1 09:36:53 INFO - mozsystemmonitor == 0.3 09:36:53 INFO - moztest == 0.8 09:36:53 INFO - mozversion == 1.4 09:36:53 INFO - psutil == 3.1.1 09:36:53 INFO - requests == 1.2.3 09:36:53 INFO - wsgiref == 0.1.2 09:36:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:53 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')]} 09:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:53 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')]} 09:36:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:53 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:53 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 09:36:53 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 09:36:53 INFO - Using env: {'DISPLAY': ':0', 09:36:53 INFO - 'HOME': '/home/cltbld', 09:36:53 INFO - 'LANG': 'en_US.UTF-8', 09:36:53 INFO - 'LANGUAGE': 'en_US:en', 09:36:53 INFO - 'LOGNAME': 'cltbld', 09:36:53 INFO - 'MAIL': '/var/mail/cltbld', 09:36:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:53 INFO - 'MOZ_NO_REMOTE': '1', 09:36:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:53 INFO - 'NO_EM_RESTART': '1', 09:36:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:53 INFO - 'PWD': '/builds/slave/test', 09:36:53 INFO - 'SHELL': '/bin/bash', 09:36:53 INFO - 'SHLVL': '1', 09:36:53 INFO - 'TERM': 'linux', 09:36:53 INFO - 'TMOUT': '86400', 09:36:53 INFO - 'USER': 'cltbld', 09:36:53 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:53 INFO - '_': '/tools/buildbot/bin/python'} 09:36:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 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)) 09:36:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:54 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:36:54 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)) 09:36:54 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)) 09:36:54 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)) 09:36:54 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)) 09:36:54 INFO - Installing collected packages: marionette-driver 09:36:54 INFO - Running setup.py install for marionette-driver 09:36:55 INFO - Successfully installed marionette-driver 09:36:55 INFO - Cleaning up... 09:36:55 INFO - Return code: 0 09:36:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:55 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')]} 09:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:55 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')]} 09:36:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:55 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 0xae81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe59e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, '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': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', '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 09:36:55 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 09:36:55 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 09:36:55 INFO - Using env: {'DISPLAY': ':0', 09:36:55 INFO - 'HOME': '/home/cltbld', 09:36:55 INFO - 'LANG': 'en_US.UTF-8', 09:36:55 INFO - 'LANGUAGE': 'en_US:en', 09:36:55 INFO - 'LOGNAME': 'cltbld', 09:36:55 INFO - 'MAIL': '/var/mail/cltbld', 09:36:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:55 INFO - 'MOZ_NO_REMOTE': '1', 09:36:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:55 INFO - 'NO_EM_RESTART': '1', 09:36:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:55 INFO - 'PWD': '/builds/slave/test', 09:36:55 INFO - 'SHELL': '/bin/bash', 09:36:55 INFO - 'SHLVL': '1', 09:36:55 INFO - 'TERM': 'linux', 09:36:55 INFO - 'TMOUT': '86400', 09:36:55 INFO - 'USER': 'cltbld', 09:36:55 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:36:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:55 INFO - '_': '/tools/buildbot/bin/python'} 09:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:36:55 INFO - Cleaning up... 09:36:55 INFO - Return code: 0 09:36:55 INFO - Running post-action listener: _resource_record_post_action 09:36:55 INFO - Running post-action listener: _start_resource_monitoring 09:36:55 INFO - Starting resource monitoring. 09:36:55 INFO - [mozharness: 2017-06-29 16:36:55.292979Z] Finished create-virtualenv step (success) 09:36:55 INFO - [mozharness: 2017-06-29 16:36:55.293536Z] Running install step. 09:36:55 INFO - Running pre-action listener: _resource_record_pre_action 09:36:55 INFO - Running main action method: install 09:36:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:55 INFO - Reading from file tmpfile_stdout 09:36:55 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:55 INFO - Reading from file tmpfile_stdout 09:36:55 INFO - Output received: 09:36:55 INFO - Usage: mozinstall [options] installer 09:36:55 INFO - Options: 09:36:55 INFO - -h, --help show this help message and exit 09:36:55 INFO - -d DEST, --destination=DEST 09:36:55 INFO - Directory to install application into. [default: 09:36:55 INFO - "/builds/slave/test"] 09:36:55 INFO - --app=APP Application being installed. [default: firefox] 09:36:55 INFO - mkdir: /builds/slave/test/build/application 09:36:55 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'] 09:36:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:37:13 INFO - Reading from file tmpfile_stdout 09:37:13 INFO - Output received: 09:37:13 INFO - /builds/slave/test/build/application/firefox/firefox 09:37:13 INFO - Running post-action listener: _resource_record_post_action 09:37:13 INFO - [mozharness: 2017-06-29 16:37:13.583063Z] Finished install step (success) 09:37:13 INFO - [mozharness: 2017-06-29 16:37:13.583342Z] Running setup-mitmproxy step. 09:37:13 INFO - Running pre-action listener: _resource_record_pre_action 09:37:13 INFO - Running main action method: setup_mitmproxy 09:37:13 INFO - Skipping: mitmproxy is not required 09:37:13 INFO - Running post-action listener: _resource_record_post_action 09:37:13 INFO - [mozharness: 2017-06-29 16:37:13.584475Z] Finished setup-mitmproxy step (success) 09:37:13 INFO - [mozharness: 2017-06-29 16:37:13.584705Z] Running run-tests step. 09:37:13 INFO - Running pre-action listener: _resource_record_pre_action 09:37:13 INFO - Running main action method: run_tests 09:37:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:13 INFO - Python 2.7.3 09:37:13 INFO - Return code: 0 09:37:13 INFO - grabbing minidump binary from tooltool 09:37: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')]} 09:37:13 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 0xe57020>, '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 0xe57f20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe583b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:13 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 09:37:13 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 09:37:13 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 09:37:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:13 INFO - Return code: 0 09:37:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:37:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:13 INFO - ENV: RUST_BACKTRACE is now 1 09:37:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:37:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:13 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/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 09:37:13 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/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 09:37:13 INFO - Using env: {'DISPLAY': ':0', 09:37:13 INFO - 'HOME': '/home/cltbld', 09:37:13 INFO - 'LANG': 'en_US.UTF-8', 09:37:13 INFO - 'LANGUAGE': 'en_US:en', 09:37:13 INFO - 'LOGNAME': 'cltbld', 09:37:13 INFO - 'MAIL': '/var/mail/cltbld', 09:37:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:13 INFO - 'MOZ_NO_REMOTE': '1', 09:37:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:13 INFO - 'NO_EM_RESTART': '1', 09:37:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:13 INFO - 'PWD': '/builds/slave/test', 09:37:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:13 INFO - 'RUST_BACKTRACE': '1', 09:37:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:37:13 INFO - 'SHELL': '/bin/bash', 09:37:13 INFO - 'SHLVL': '1', 09:37:13 INFO - 'TERM': 'linux', 09:37:13 INFO - 'TMOUT': '86400', 09:37:13 INFO - 'USER': 'cltbld', 09:37:13 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187', 09:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:13 INFO - '_': '/tools/buildbot/bin/python'} 09:37:13 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/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 09:37:13 INFO - mozversion application_buildid: 20170629155230 09:37:13 INFO - mozversion application_changeset: f3483af8ecf997453064201c49c48a682c7f3c29 09:37:13 INFO - mozversion application_display_name: Nightly 09:37:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:13 INFO - mozversion application_name: Firefox 09:37:13 INFO - mozversion application_remotingname: firefox 09:37:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:37:13 INFO - mozversion application_vendor: Mozilla 09:37:13 INFO - mozversion application_version: 56.0a1 09:37:13 INFO - mozversion platform_buildid: 20170629155230 09:37:13 INFO - mozversion platform_changeset: f3483af8ecf997453064201c49c48a682c7f3c29 09:37:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:37:13 INFO - mozversion platform_version: 56.0a1 09:37:13 INFO - using testdate: 1498754233 09:37:13 INFO - actual date: 1498754233 09:37:13 INFO - starting webserver on 'localhost:54747' 09:37:13 INFO - SUITE-START | Running 2 tests 09:37:13 INFO - TEST-START | dromaeo_css 09:37:13 INFO - operating with platform_type : linux_ 09:37:13 INFO - Initialising browser for dromaeo_css test... 09:37:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54747/getInfo.html -profile /tmp/tmpWuHWIj/profile 09:37:13 INFO - TEST-INFO | started process 18872 (/builds/slave/test/build/application/firefox/firefox http://localhost:54747/getInfo.html) 09:37:19 INFO - TEST-INFO | 18872: exit 0 09:37:20 INFO - Browser initialized. 09:37:20 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:20 INFO - TEST-INFO | started process 19091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWuHWIj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:31 INFO - PID 19091 | RSS: Main: 172060672 09:37:31 INFO - PID 19091 | 09:40:35 INFO - PID 19091 | Cycle 1(1): loaded http://localhost:54747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54747/tests/dromaeo/cssquery-ext.html) 09:40:37 INFO - PID 19091 | RSS: Main: 178761728 09:40:37 INFO - PID 19091 | 09:43:39 INFO - PID 19091 | Cycle 1(1): loaded http://localhost:54747/tests/dromaeo/cssquery-ext.html (next: http://localhost:54747/tests/dromaeo/cssquery-jquery.html) 09:43:39 INFO - PID 19091 | RSS: Main: 175562752 09:43:39 INFO - PID 19091 | 09:46:41 INFO - PID 19091 | Cycle 1(1): loaded http://localhost:54747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54747/tests/dromaeo/cssquery-mootools.html) 09:46:42 INFO - PID 19091 | RSS: Main: 178995200 09:46:42 INFO - PID 19091 | 09:49:44 INFO - PID 19091 | Cycle 1(1): loaded http://localhost:54747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54747/tests/dromaeo/cssquery-prototype.html) 09:49:44 INFO - PID 19091 | RSS: Main: 177733632 09:49:44 INFO - PID 19091 | 09:52:47 INFO - PID 19091 | Cycle 1(1): loaded http://localhost:54747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54747/tests/dromaeo/cssquery-yui.html) 09:52:48 INFO - PID 19091 | RSS: Main: 177504256 09:52:48 INFO - PID 19091 | 09:55:49 INFO - PID 19091 | Cycle 1(1): loaded http://localhost:54747/tests/dromaeo/cssquery-yui.html (next: http://localhost:54747/tests/dromaeo/cssquery-dojo.html) 09:55:50 INFO - PID 19091 | RSS: Main: 180875264 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | __start_tp_report 09:55:50 INFO - PID 19091 | _x_x_mozilla_page_load 09:55:50 INFO - PID 19091 | _x_x_mozilla_page_load_details 09:55:50 INFO - PID 19091 | |i|pagename|runs| 09:55:50 INFO - PID 19091 | |0;dojo.html;3021.8640161192743;3075.8769649214028;3093.1184612385473;3103.3793241351727;3115.26791204067;5966.403359664031;6007.909881351783;6011.879762404755;6024.87950240996;6059.757609695613;6008.429199226078;6022.849428764283;6025.819225423234;6102.450779429851;6120.1125836753645;5547.140193270039;5568.86077848053;5636.661800291996;5666.121751128581;5710.543156547487;5666.744996475148;5678.318601767773;5685.687287199203;5726.112452569838;5729.341125770548;5656.773729050833;5663.377028526858;5753.65478071315;5779.393163717812;5786.189933409305;5701.543876489871;5725.169850371714;5744.396838331957;5748.137779333092;5754.827355179351;5618.38197798246;5668.97165514174;5718.714064296769;5729.627574207663;5747.856303592402;5959;5969.164316995624;6056.424639659225;6083.361247069039;6116.388361163892;6432;6485.416312531847;6497.870042599169;6504.9349506504805;6513.511486638521;6646.933530664728;6670.599764014126;6672.566283191625;6682.064510968468;6690.933090669128;6196.628202307876;6197.101420294122;6199;6205.100260462208;6212.347703491158;6811.012403201508;6818.28408017161;6821.386075253152;6826.692798824065;6826.863462730717;6271.090691849657;6381.1066450696935;6382.648954307558;6399.552031357854;6504.804855854332;6559;6580.473562115019;6624.337566243337;6634.86730265392;6638.904580744123;6422.197225346832;6645.468362530986;6664.566803157779;6681.198256209286;6695.330466953363;6511;6551.770688025897;6565;6578.046183303394;6581.80254592363;5298.470152984671;5329.413764485904;5340.172273297645;5341.893162136736;5369.838904832861;4602.631789456836;4625;4636.374089497875;4656.301554766745;4669.229577119737;5722.084466485342;5764.682942438122;5768.048272035151;5769.596128271065;5786.855328616818;6273.247210334789;6454.419102280724;6529.640869752209;6655.667216639149;6717.899231511531;5669.461401166965;5729.140628905698;5760.3951585083805;5785.797497087582;5796;5767.711614419262;5831.06702927538;5875.823725288248;5904.675242861598;5924.11138329254;6435.292117867125;6491.578047426902;6491.837704057436;6498.837529061622;6506.316836732264;6725.495587830835;6727;6739.191297044386;6740.6966686498245;6740.73037078531;6195.659238741912;6422.068800023877;6423.164988551458;6431.614103153825;6434.710438030394;6314.147590075281;6363.013732871413;6392.552521323462;6454.935450645434;6476.676166191766;6712.865742685217;6741.02255173007;6744.494162937701;6746.224184218878;6766.4248538874745;6506.41442270198;6528.6735663215695;6537.477001839747;6540.8364790880605;6546.738130474728;6513.446357059703;6513.609183449008;6519.706613202512;6522.184726909137;6535.3464653534265;6245.96877015612;6311.526635502264;6400.295967443671;6417.614943103429;6488.675566221765;6271.43557079865;6431.485481161402;6444.323346048597;6445.774397896239;6449.5485316027425;6346.555741098079;6358.523110766619;6362.236531616236;6386.361363863576;6387.552871298964 09:55:50 INFO - PID 19091 | |1;ext.html;802.1978021978022;817.8078151634361;841.4572600672566;859.4241857955169;867.2801574693003;36921.63078369219;37514.99999999996;37666.623333766656;37802.8109859451;37823.81088094559;36726.449103263476;37025.444618330745;37175.25649487009;37227;37306.62693373065;6713.966430167842;6736.562123461984;6800.353966373186;6806.285340039274;6806.523543351967;6752.324767523258;6769.0861733665815;6865.4850886183485;6868.038474613558;6874.140732408449;6797.422219111381;6809.625470599115;6840.179178498587;6840.623765692885;6841.350071743202;6807.829804254884;6811.965940170292;6814.897776533381;6817.965910170467;6871.209810871764;6143.662098584576;6188;6206.72069756862;6207.81376558704;6213.471854892295;32972.83513582441;33269;33304.50043249353;33443.83278083619;33510.832445837856;3294.8187849668257;3340.1983523954077;3345.46472564391;3456.550648415715;3526.9118272043147;31102.844485777427;31231.219219519466;31304.21739456509;31346.37307253865;31491.685083149332;4701.318308845232;4864.635152363551;4923.138450771136;4959.603231741488;5038.949610503884;189524.0523797386;191719;193353.9999999986;194690;196577;47216.05567888623;47511.287330690066;47816.32642857483;48381;48553.27170092452;49460.75269623629;49665.255021174715;49844.25233621499;49890.50109498931;49952.25071623929;16324.265408056428;16399.18004099814;16415.671686566202;16415.753763693556;16499.5875103121;13661.316934153252;13736.381862816197;13749.656258593615;13828.101173423694;13850.93074534641;13603.115797473132;13750.243736594583;13816.516421925386;13852.653683657787;14018.649533761738;5475.835724928259;5556.91664625031;5603.243562119062;5616.85957851057;5794.420557944172;977.628501169551;1002.7543251903185;1003.8745156855393;1034.2915103154364;1052.921030922684;22153.889230553745;22535.774642253695;22818.31545053651;23310;23395.41511462193;1080.3949788118678;1100.0759362135843;1103.9172062095374;1126.774645070989;1128.3004537186832;11706.297622142698;11738.500615152543;11974.64076077719;12062.577809776616;12360.75278494443;231586;231952;232341;232355;233820;51915.480845191065;52189.73905130526;52251;52509;52855.73572132115;1421.7298713244656;1446.9509605536114;1472.8232612086617;1481.1927499512792;1484.5026915983267;21908;22015.889920550293;22626;22894.427639308484;27420.588691170047;49360.25959610537;49451.50548494613;49862.750686246334;49957.75021124871;49996;195598;198051;198695;199620;200115;35527.82236088803;35535.644643553234;35590.8220458896;37513.249735005695;37877.24245515019;44580.33129503125;46300;46334.768326158155;46394;47215.76392118017;31772.682273177896;33701.74307294728;33821;34161.82919085389;34709.47935780915;31987;32756.181095472803;33239.83380083084;33275.16812079717;33674.83162584171;35722.464163038094;37777.433338499395;37797.62202377941;37868.05329866776;37887.242255155295;30748.077557673307;31534.36931261408;32562.674373255963;32872.50691239585;33059.173520662174;23936.760632393452;23974.40063998414;24084;24289.27132186037;24332.635010475213 09:55:50 INFO - PID 19091 | |2;jquery.html;565.2171742137139;567.2483958754651;576.7260551238162;578.1414599320009;592.3987153039665;15879;21300.57398852024;21914.12343506258;22021.559568808636;22040;18892.716609250838;19252.903735481304;22267.55464890701;23297.417564560896;24879.75120248797;6282.842928926779;6446.806595802133;6480.254770701359;6486.643234622117;6499.870002599945;6339.080833279195;6409.455196308296;6447.451966582823;6457.031445283198;6460.515461340395;3793.886183414502;4118.670506359499;6452.7418903243815;6455.354464553554;6524.347565243485;6425.357464253584;6462.224533056016;6468.773592924249;6490;6511.609303441785;6052.031674932034;6052.122442245894;6067.453929146354;6070.2412198475195;6075.2405949256345;27417.177484675492;27594.72405275962;27866.58200127;27887.302817429725;27984.860075699493;1920.7887132415563;2001.099505222641;2015.1838505405335;2023.5548179400507;2034.2778313698573;17015.319387224496;17510.124493775387;17552.122393880254;17587.824121758746;17592.736108958376;2196.2313190383397;2315.7336906255828;2323.2217207235594;2336.497653004612;2363.9054437822465;128725;133343.33328333296;133351;133714.3314283432;134056.32971835177;13256.668583285302;13960.720785584232;13976;14133.85866141346;14334.569962900921;22634.66048009281;22649.433764156027;22745;22749.772502275096;22752.976116074486;9753.341931871599;10010.749731256778;10054.396736195851;10114.089731924016;10129.94935025335;9072.591733372014;9074.410163339362;9075.364724469224;9182.311326650528;9208.447493150432;8486.19381158789;8509.411261695663;8519.275861551838;8598.39811213209;8730.825383492294;2179.607670619304;2548.107633343984;2594.442194928062;2686.2747058294153;2782.65216847894;987.9950600246952;993.4536005197257;1001.2740762946805;1021.6322124035341;1043.5929987304958;18205.7269140963;18461.1692473839;18795.15421806022;20954.895225523775;22614.434639133826;1023.9846402303972;1040.292601031306;1062.8352605346183;1072.2279958429917;1093.9507722152523;10306.845397319046;10562.366258024544;10597.470126493645;10658;10664.360138391721;482952.75570866466;496479;508740;513933;515116;109380.453097734;109741.45129274302;110035.44982275038;111553.44223278832;112266;1234.4321612058554;1291.225264841088;1306.8235788168665;1306.9740253900832;1308.0712693987186;15821.683566328838;18339.35812246565;19050.142743576296;19520;20126.597468050266;47467.28799067948;50784.492155077976;51312.230316544534;51347.2297915539;52320;61797.69101154465;62141;62315;62765.68617157068;63175.6841215791;12173.147879648339;12263.93868030654;12558.811617825922;12568.74862502763;12596.748065038832;15609.297581609082;15995.680086397973;16382.672346553241;16429.932054416426;16509.17454127313;13792.724145516833;13982;14021.859781402056;14119.152850828572;14156.150630962175;12735.108542401942;12870.806937895752;13234.867651323364;13317.733645327233;13523.729525409633;13996.090254133449;14127.64680882986;14243.928780356031;14292;14334.784978225127;12366.44351004189;12568.057395695176;12574.371281436075;12591.433385497858;12600.810987835006;13929.373178207208;13944.442222310994;14113.647158821112;14151.363188656742;14154.929225354219 09:55:50 INFO - PID 19091 | |3;mootools.html;1305.1907817153365;1375.6079517337562;1378.1937566523577;1387.7155183187447;1395.4557722488237;13295.135816171955;13737.313134343292;14072.929635351822;14088.084274522149;14109.858901410982;12983.350832458387;13389;13506.797398039012;13602.115862468932;13719.314034298295;2650.469906018804;2653.4958357912033;2697.5953606959015;2702.540568103427;2707.241972247774;2545.567253566898;2621.2529429112706;2624.278323461054;2624.3045592917892;2624.46198529302;2564.8204625676212;2599.3891435512637;2620.344913771557;2620.9475810483777;2620.9606855897177;2477.950440991179;2522.066835270952;2529.519391315653;2535.1253817432957;2539.1240022192314;2484.441000774811;2489.054159419409;2533.949321013588;2595.7663810256977;2611.8302310349954;13661.453541858315;13680.384382702803;13730.313484325743;13772.10481318711;13787.86212137886;7001.439884809203;7246.746363877239;7250.166230883463;7259;7290.890636640454;4744.620430365562;4825.76942879567;4829.106615276185;4893.04585605807;4967.975160124212;1734.4000954715714;1763.9823601763946;1816.0465755478347;1825.6713791517523;1858.4053103007043;21705.13179472835;21899.562008759895;21999.450013749625;22061.779382206292;23082.884585577132;6331.113644089831;6410.45511131559;6611.735530578723;6616.768413105518;6685.39831415175;15604.219789010504;15653.60865978337;15655.060696358254;15674.529764107096;15675;7266.164391094986;7329.706811727578;7350.816229594196;7355.007074045043;7359.227281135511;6712.597244165366;6775.254721980578;6824.317568243136;6897.241303456616;6952.965235173893;4781.593564546983;4900.289458028566;4911.533404326583;4970.627202959792;5036.345275114285;1400.4188261871413;1420.9963950829488;1447.486142419447;1449.6303442622275;1454.6581553334959;885.6413152673049;887.4187407248263;898.8651702244584;900.9324300677345;920.6087412849512;6776.627285499345;6951.2353641099435;6952.513324067368;6988.37104660583;7026.402755765715;678.8370791010123;691.8477934854324;703.6270776488469;715.5027256057094;733.2740586819117;4415.249407600716;4701.576858082721;4982.352294201731;5055.115354812967;5273.6572122811895;17408.39070632522;18444.89330640166;18576.25694972213;18646.44060678182;18661;17362.565935851704;18418.631627367376;18419.815801842076;18468.353607623667;18679.06604669761;9800.460974646463;10080.445575493419;10293.279470437148;10378;10382.325148865451;7393.223711510429;7481.214472480311;7750.186230445947;7755.418343624364;7927.207279272257;15525.689486209987;15854.7621785671;16108.67782644364;16330;16536.834631654012;15666.138362390178;16142.435014774896;16500.42248521296;16625.50123496297;16666.33334666648;9235.445873247627;9894.455804930798;10002.399856008371;10019.849702254327;10028.64899728506;11949.522019119138;11958.10314226454;11962.700932476757;12033.4584943676;12126.514939402678;9871.950640246752;10215.489225538544;10313.896861031593;10378.377297362182;10581.4709264538;10186.949065254626;11007.174461915229;11104.888951110386;11241;11348.659540213806;8861.468311901306;9081;9174.174324310845;9205.263578913804;9240.214581760625;6886.311368863074;6988.860222795617;7188.173360063451;7197.676104575202;7244.492885497964;6963.268856769966;7086.39765619928;7121.928780712334;7124.002639630559;7192.31672991065 09:55:50 INFO - PID 19091 | |4;prototype.html;485.99757001214965;495.3535635995026;514.7297668723921;518.6602775182256;519.1900635009383;6163.784267550636;6213.813585592428;6220.667249781332;6225.657588832612;6298.842528936779;5952.642841429517;6175.289841668209;6179.66011869347;6190.1643278157435;6196.535259855506;4983.177775667011;4995.825146119886;4996.725180115092;5011.599072074226;5025.296458495814;4997.025580011881;5034.194528875362;5038.118329292378;5040.420351659551;5044.319016932704;4790.473047964721;4968.527989840959;4980.97509512452;4993;5006.824761133361;4921.950780492203;4980;4983.230953011701;4989.226669866177;4991.376077990251;4415.712978656377;4444.488883778375;4470.977645111786;4472.597466228055;4477.306017567283;5948.167256584082;6009.519238460913;6013.428724271187;6027.4273943975695;6036.5170786336985;5703.258576385085;5724.284464441945;5724.914126288109;5738.6556806591325;5750.367459579485;6534.575252608565;6544.018397240405;6549.901751473732;6575.178102737158;6585.769498067592;4832.299316599108;4880.2435622478215;4897.730624815634;4900.901981960341;4933;6878;6916.965415172942;6947.513674042819;6959.269276725972;6964.860702785916;5044.369453818273;5068.113080210948;5073.467285934924;5075.01611235186;5080.872978175576;5090.803661139629;5102.668326558762;5103.438621751604;5154.7938082476285;5156.767945442464;5698.8290351289525;5770.105633626795;5777.768889244383;5780.479756821906;5784.363719990798;6578.835529111715;6653.833654158685;6658.4340331071435;6669.4330981866115;6695.732170713117;1749.7550342951997;1809.1587411853288;1961.8234358907766;1967.3704414587194;1972.2012584903136;1375.3604573873197;1463.27567853913;1482.5626440200167;1504.9398024078914;1507.2765072765135;5613.101903695389;5648.322201335866;5727.828165155052;5729.7708091676695;5768.625039372428;6786.151731033621;6793.116894803644;6795.52431329812;6804.251532331439;6862.451003919675;5731.140328950691;5786.88426231473;5800.1299805029585;5806.68063256525;5817.767289308465;3581.28374325136;3598.8020658864016;3604.225091605265;3606.85572577094;3619.2761447710564;6568.540202185896;6571.342865713486;6572.11276477679;6575.605463672195;6580.894113883607;6847.246802851682;6956.826079347956;6961.791146265519;6980.895286570705;6982.93017069833;6483.675816209171;6521.315261897527;6522.478201743849;6568.737250510022;6588.308227636029;6754.189497260164;6768.187817461936;6773.322667733188;6787.62668053242;6815.965920170367;6666;6698.698558564974;6709.194896612437;6711;6741;6204.472619827366;6503.837404064936;6545.672716364067;6579.703913324008;6608.8347791305605;4879.170541007966;4957.628177886745;4994.375703037121;5007.724575148256;5033.924491132562;6699.162604674416;6777.45780337562;6804.013418054454;6815.386615204656;6843.144606924135;6561.8988132417235;6571.671416429063;6574;6582.605043697394;6587.209534855656;6490.578112422678;6561.704723287559;6561.934380656132;6580.473562115115;6585.539012269095;6481.902771458526;6498.902516462163;6517.9348206517325;6540.500832054828;6545.967270163619;6642.269350371552;6645;6648.434883034996;6657.234418041793;6659.500537459807;6452.677366131581;6457.160569125984;6469;6470.838229044311;6500.797352489804 09:55:50 INFO - PID 19091 | |5;yui.html;909.8180363927216;931.1340453378358;950.6957773512479;957.8639633617034;971.1696499492931;16044.117573533465;16955.660886782272;16988.74516882248;17029.82970170298;17086.658266834656;14483.927580362084;14545.345459454324;16655.1672416379;17776.200070996805;19380;1018.1498448795246;1035.373598972622;1039.0700323210722;1045;1046.3512622174262;1045.5765415006933;1048.847917052027;1051.285126114242;1051.4479898053544;1052.8683914510686;1112.2714621922614;1115.9888401115966;1116.7878103160415;1118.2004866520476;1121.6803211084843;1036.7615448446825;1076.5747529725793;1094.7317907112767;1105.287089827061;1108.9445527723622;783.5142211828646;785.8978332816754;785.9292663660241;786.7718361675107;786.9488483248628;17641.735373969273;17672.646547069115;17810.91094544532;17815.376461823904;17816.37642682513;18896.5275868103;20288.289909853083;20319.18723251068;20378.592428151354;20764.792352076438;14810.481633142788;15373.769393459108;15570.455034073866;15589.688206235925;15599.610009749733;3557.8043207623828;3568.536090308269;3576.6065732769375;3582.247727977128;3588.6770190682964;104754.47622761913;108430.45784771105;108734;108942;111040.88959110386;15621.609459763597;16197.271122799502;16591.25339359732;16626.334946602;16781.07704076263;28429.71570284312;28608.427831443256;29157;29403.558946615816;29578;8691.608877600524;8727.869081963776;8744.825103497895;8755.387122901464;8760.211580957744;4045.453863728359;4140.37894315855;4162.771047592351;4168.895777605584;4175.164967006611;4709.3877795887;4885.413750349981;4975.90048199034;4981.900361992813;5012.04771093491;1014.6550172941236;1029.953652085658;1039.6465201831263;1058.4654749351682;1058.9886658241514;671.1979184874126;674.4806498995746;676.4013847744782;708.8121647763347;712.0636363182399;12008.279503229835;12457.1902826316;12837.550685725955;12903.290319032543;13258.73482530344;653.6470306034688;667.8864593019198;668.1147479589563;670.0150778355809;697.6790676288863;5092.770825312869;5133.717645529457;5179.7928082877015;5311.49540793624;5380.488853558905;97028;98223;104626.47686761517;106082;106787.46606267075;69022.65488672524;87433.37699868887;87732.5613371929;89737;92062.53968730113;76655.61672191715;81004.26431857418;82638;84342.5782871094;85885;11551.191416600757;11806.527738890692;11893.821592675944;11965.222260553037;12218.51125954952;33844.492332614536;34295.828520857234;34389.62441502312;35051.47422788711;35987.560497579805;42494.36258456064;44945.775271123435;45176.77411612921;46066;46474.07051859012;17681.557961051076;18028.909855450638;18176;18196.636067278316;18529.90735046316;20347.593048138657;20400.795992040483;20604.27885024017;20627.968601570163;20840.791592083886;17797.733034004243;18294.176762045474;18489.63020739551;18910.338138165098;18995.620087598447;17651.55871103233;17752.55618609545;18050.548736281697;18140.81859181444;18174.72737908906;17140.314387424365;17547.385841495485;17682.823171768632;18017.459476215736;18258.26966921308;11492.885071149181;12328.568500102454;12537.310447925087;12861.842434180968;13084.869151308365;12000.699982500508;12576.182548134342;12598.433070512032;12647.430865610886;12762.553310634083 09:55:50 INFO - PID 19091 | __end_tp_report 09:55:50 INFO - PID 19091 | __start_cc_report 09:55:50 INFO - PID 19091 | _x_x_mozilla_cycle_collect,359 09:55:50 INFO - PID 19091 | __end_cc_report 09:55:50 INFO - PID 19091 | __startTimestamp1498755350124__endTimestamp 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | ------- Summary: start ------- 09:55:50 INFO - PID 19091 | Number of tests: 6 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | [#0] dojo.html Cycles:165 Average:6086.22 Median:6346.56 stddev:715.61 (11.3%) stddev-sans-first:676.22 09:55:50 INFO - PID 19091 | Values: 3021.9 3075.9 3093.1 3103.4 3115.3 5966.4 6007.9 6011.9 6024.9 6059.8 6008.4 6022.8 6025.8 6102.5 6120.1 5547.1 5568.9 5636.7 5666.1 5710.5 5666.7 5678.3 5685.7 5726.1 5729.3 5656.8 5663.4 5753.7 5779.4 5786.2 5701.5 5725.2 5744.4 5748.1 5754.8 5618.4 5669.0 5718.7 5729.6 5747.9 5959.0 5969.2 6056.4 6083.4 6116.4 6432.0 6485.4 6497.9 6504.9 6513.5 6646.9 6670.6 6672.6 6682.1 6690.9 6196.6 6197.1 6199.0 6205.1 6212.3 6811.0 6818.3 6821.4 6826.7 6826.9 6271.1 6381.1 6382.6 6399.6 6504.8 6559.0 6580.5 6624.3 6634.9 6638.9 6422.2 6645.5 6664.6 6681.2 6695.3 6511.0 6551.8 6565.0 6578.0 6581.8 5298.5 5329.4 5340.2 5341.9 5369.8 4602.6 4625.0 4636.4 4656.3 4669.2 5722.1 5764.7 5768.0 5769.6 5786.9 6273.2 6454.4 6529.6 6655.7 6717.9 5669.5 5729.1 5760.4 5785.8 5796.0 5767.7 5831.1 5875.8 5904.7 5924.1 6435.3 6491.6 6491.8 6498.8 6506.3 6725.5 6727.0 6739.2 6740.7 6740.7 6195.7 6422.1 6423.2 6431.6 6434.7 6314.1 6363.0 6392.6 6454.9 6476.7 6712.9 6741.0 6744.5 6746.2 6766.4 6506.4 6528.7 6537.5 6540.8 6546.7 6513.4 6513.6 6519.7 6522.2 6535.3 6246.0 6311.5 6400.3 6417.6 6488.7 6271.4 6431.5 6444.3 6445.8 6449.5 6346.6 6358.5 6362.2 6386.4 6387.6 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | [#1] ext.html Cycles:180 Average:37771.53 Median:18809.19 stddev:54172.94 (288.0%) stddev-sans-first:54253.79 09:55:50 INFO - PID 19091 | Values: 802.2 817.8 841.5 859.4 867.3 36921.6 37515.0 37666.6 37802.8 37823.8 36726.4 37025.4 37175.3 37227.0 37306.6 6714.0 6736.6 6800.4 6806.3 6806.5 6752.3 6769.1 6865.5 6868.0 6874.1 6797.4 6809.6 6840.2 6840.6 6841.4 6807.8 6812.0 6814.9 6818.0 6871.2 6143.7 6188.0 6206.7 6207.8 6213.5 32972.8 33269.0 33304.5 33443.8 33510.8 3294.8 3340.2 3345.5 3456.6 3526.9 31102.8 31231.2 31304.2 31346.4 31491.7 4701.3 4864.6 4923.1 4959.6 5038.9 189524.1 191719.0 193354.0 194690.0 196577.0 47216.1 47511.3 47816.3 48381.0 48553.3 49460.8 49665.3 49844.3 49890.5 49952.3 16324.3 16399.2 16415.7 16415.8 16499.6 13661.3 13736.4 13749.7 13828.1 13850.9 13603.1 13750.2 13816.5 13852.7 14018.6 5475.8 5556.9 5603.2 5616.9 5794.4 977.6 1002.8 1003.9 1034.3 1052.9 22153.9 22535.8 22818.3 23310.0 23395.4 1080.4 1100.1 1103.9 1126.8 1128.3 11706.3 11738.5 11974.6 12062.6 12360.8 231586.0 231952.0 232341.0 232355.0 233820.0 51915.5 52189.7 52251.0 52509.0 52855.7 1421.7 1447.0 1472.8 1481.2 1484.5 21908.0 22015.9 22626.0 22894.4 27420.6 49360.3 49451.5 49862.8 49957.8 49996.0 195598.0 198051.0 198695.0 199620.0 200115.0 35527.8 35535.6 35590.8 37513.2 37877.2 44580.3 46300.0 46334.8 46394.0 47215.8 31772.7 33701.7 33821.0 34161.8 34709.5 31987.0 32756.2 33239.8 33275.2 33674.8 35722.5 37777.4 37797.6 37868.1 37887.2 30748.1 31534.4 32562.7 32872.5 33059.2 23936.8 23974.4 24084.0 24289.3 24332.6 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | [#2] jquery.html Cycles:180 Average:33300.62 Median:2019.37 stddev:84453.20 (4182.2%) stddev-sans-first:84654.35 09:55:50 INFO - PID 19091 | Values: 565.2 567.2 576.7 578.1 592.4 15879.0 21300.6 21914.1 22021.6 22040.0 18892.7 19252.9 22267.6 23297.4 24879.8 6282.8 6446.8 6480.3 6486.6 6499.9 6339.1 6409.5 6447.5 6457.0 6460.5 3793.9 4118.7 6452.7 6455.4 6524.3 6425.4 6462.2 6468.8 6490.0 6511.6 6052.0 6052.1 6067.5 6070.2 6075.2 27417.2 27594.7 27866.6 27887.3 27984.9 1920.8 2001.1 2015.2 2023.6 2034.3 17015.3 17510.1 17552.1 17587.8 17592.7 2196.2 2315.7 2323.2 2336.5 2363.9 128725.0 133343.3 133351.0 133714.3 134056.3 13256.7 13960.7 13976.0 14133.9 14334.6 22634.7 22649.4 22745.0 22749.8 22753.0 9753.3 10010.7 10054.4 10114.1 10129.9 9072.6 9074.4 9075.4 9182.3 9208.4 8486.2 8509.4 8519.3 8598.4 8730.8 2179.6 2548.1 2594.4 2686.3 2782.7 988.0 993.5 1001.3 1021.6 1043.6 18205.7 18461.2 18795.2 20954.9 22614.4 1024.0 1040.3 1062.8 1072.2 1094.0 10306.8 10562.4 10597.5 10658.0 10664.4 482952.8 496479.0 508740.0 513933.0 515116.0 109380.5 109741.5 110035.4 111553.4 112266.0 1234.4 1291.2 1306.8 1307.0 1308.1 15821.7 18339.4 19050.1 19520.0 20126.6 47467.3 50784.5 51312.2 51347.2 52320.0 61797.7 62141.0 62315.0 62765.7 63175.7 12173.1 12263.9 12558.8 12568.7 12596.7 15609.3 15995.7 16382.7 16429.9 16509.2 13792.7 13982.0 14021.9 14119.2 14156.2 12735.1 12870.8 13234.9 13317.7 13523.7 13996.1 14127.6 14243.9 14292.0 14334.8 12366.4 12568.1 12574.4 12591.4 12600.8 13929.4 13944.4 14113.6 14151.4 14154.9 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | [#3] mootools.html Cycles:180 Average:8354.36 Median:2580.29 stddev:5670.32 (219.8%) stddev-sans-first:5661.49 09:55:50 INFO - PID 19091 | Values: 1305.2 1375.6 1378.2 1387.7 1395.5 13295.1 13737.3 14072.9 14088.1 14109.9 12983.4 13389.0 13506.8 13602.1 13719.3 2650.5 2653.5 2697.6 2702.5 2707.2 2545.6 2621.3 2624.3 2624.3 2624.5 2564.8 2599.4 2620.3 2620.9 2621.0 2478.0 2522.1 2529.5 2535.1 2539.1 2484.4 2489.1 2533.9 2595.8 2611.8 13661.5 13680.4 13730.3 13772.1 13787.9 7001.4 7246.7 7250.2 7259.0 7290.9 4744.6 4825.8 4829.1 4893.0 4968.0 1734.4 1764.0 1816.0 1825.7 1858.4 21705.1 21899.6 21999.5 22061.8 23082.9 6331.1 6410.5 6611.7 6616.8 6685.4 15604.2 15653.6 15655.1 15674.5 15675.0 7266.2 7329.7 7350.8 7355.0 7359.2 6712.6 6775.3 6824.3 6897.2 6953.0 4781.6 4900.3 4911.5 4970.6 5036.3 1400.4 1421.0 1447.5 1449.6 1454.7 885.6 887.4 898.9 900.9 920.6 6776.6 6951.2 6952.5 6988.4 7026.4 678.8 691.8 703.6 715.5 733.3 4415.2 4701.6 4982.4 5055.1 5273.7 17408.4 18444.9 18576.3 18646.4 18661.0 17362.6 18418.6 18419.8 18468.4 18679.1 9800.5 10080.4 10293.3 10378.0 10382.3 7393.2 7481.2 7750.2 7755.4 7927.2 15525.7 15854.8 16108.7 16330.0 16536.8 15666.1 16142.4 16500.4 16625.5 16666.3 9235.4 9894.5 10002.4 10019.8 10028.6 11949.5 11958.1 11962.7 12033.5 12126.5 9872.0 10215.5 10313.9 10378.4 10581.5 10186.9 11007.2 11104.9 11241.0 11348.7 8861.5 9081.0 9174.2 9205.3 9240.2 6886.3 6988.9 7188.2 7197.7 7244.5 6963.3 7086.4 7121.9 7124.0 7192.3 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | [#4] prototype.html Cycles:180 Average:5550.46 Median:6100.15 stddev:1533.44 (25.1%) stddev-sans-first:1489.89 09:55:50 INFO - PID 19091 | Values: 486.0 495.4 514.7 518.7 519.2 6163.8 6213.8 6220.7 6225.7 6298.8 5952.6 6175.3 6179.7 6190.2 6196.5 4983.2 4995.8 4996.7 5011.6 5025.3 4997.0 5034.2 5038.1 5040.4 5044.3 4790.5 4968.5 4981.0 4993.0 5006.8 4922.0 4980.0 4983.2 4989.2 4991.4 4415.7 4444.5 4471.0 4472.6 4477.3 5948.2 6009.5 6013.4 6027.4 6036.5 5703.3 5724.3 5724.9 5738.7 5750.4 6534.6 6544.0 6549.9 6575.2 6585.8 4832.3 4880.2 4897.7 4900.9 4933.0 6878.0 6917.0 6947.5 6959.3 6964.9 5044.4 5068.1 5073.5 5075.0 5080.9 5090.8 5102.7 5103.4 5154.8 5156.8 5698.8 5770.1 5777.8 5780.5 5784.4 6578.8 6653.8 6658.4 6669.4 6695.7 1749.8 1809.2 1961.8 1967.4 1972.2 1375.4 1463.3 1482.6 1504.9 1507.3 5613.1 5648.3 5727.8 5729.8 5768.6 6786.2 6793.1 6795.5 6804.3 6862.5 5731.1 5786.9 5800.1 5806.7 5817.8 3581.3 3598.8 3604.2 3606.9 3619.3 6568.5 6571.3 6572.1 6575.6 6580.9 6847.2 6956.8 6961.8 6980.9 6982.9 6483.7 6521.3 6522.5 6568.7 6588.3 6754.2 6768.2 6773.3 6787.6 6816.0 6666.0 6698.7 6709.2 6711.0 6741.0 6204.5 6503.8 6545.7 6579.7 6608.8 4879.2 4957.6 4994.4 5007.7 5033.9 6699.2 6777.5 6804.0 6815.4 6843.1 6561.9 6571.7 6574.0 6582.6 6587.2 6490.6 6561.7 6561.9 6580.5 6585.5 6481.9 6498.9 6517.9 6540.5 6546.0 6642.3 6645.0 6648.4 6657.2 6659.5 6452.7 6457.2 6469.0 6470.8 6500.8 09:55:50 INFO - PID 19091 | 09:55:50 INFO - PID 19091 | [#5] yui.html Cycles:180 Average:21318.38 Median:18227.45 stddev:28154.30 (154.5%) stddev-sans-first:28191.58 09:55:50 INFO - PID 19091 | Values: 909.8 931.1 950.7 957.9 971.2 16044.1 16955.7 16988.7 17029.8 17086.7 14483.9 14545.3 16655.2 17776.2 19380.0 1018.1 1035.4 1039.1 1045.0 1046.4 1045.6 1048.8 1051.3 1051.4 1052.9 1112.3 1116.0 1116.8 1118.2 1121.7 1036.8 1076.6 1094.7 1105.3 1108.9 783.5 785.9 785.9 786.8 786.9 17641.7 17672.6 17810.9 17815.4 17816.4 18896.5 20288.3 20319.2 20378.6 20764.8 14810.5 15373.8 15570.5 15589.7 15599.6 3557.8 3568.5 3576.6 3582.2 3588.7 104754.5 108430.5 108734.0 108942.0 111040.9 15621.6 16197.3 16591.3 16626.3 16781.1 28429.7 28608.4 29157.0 29403.6 29578.0 8691.6 8727.9 8744.8 8755.4 8760.2 4045.5 4140.4 4162.8 4168.9 4175.2 4709.4 4885.4 4975.9 4981.9 5012.0 1014.7 1030.0 1039.6 1058.5 1059.0 671.2 674.5 676.4 708.8 712.1 12008.3 12457.2 12837.6 12903.3 13258.7 653.6 667.9 668.1 670.0 697.7 5092.8 5133.7 5179.8 5311.5 5380.5 97028.0 98223.0 104626.5 106082.0 106787.5 69022.7 87433.4 87732.6 89737.0 92062.5 76655.6 81004.3 82638.0 84342.6 85885.0 11551.2 11806.5 11893.8 11965.2 12218.5 33844.5 34295.8 34389.6 35051.5 35987.6 42494.4 44945.8 45176.8 46066.0 46474.1 17681.6 18028.9 18176.0 18196.6 18529.9 20347.6 20400.8 20604.3 20628.0 20840.8 17797.7 18294.2 18489.6 18910.3 18995.6 17651.6 17752.6 18050.5 18140.8 18174.7 17140.3 17547.4 17682.8 18017.5 18258.3 11492.9 12328.6 12537.3 12861.8 13084.9 12000.7 12576.2 12598.4 12647.4 12762.6 09:55:50 INFO - PID 19091 | -------- Summary: end -------- 09:55:50 INFO - PID 19091 | 09:55:50 INFO - TEST-INFO | 19091: exit 0 09:55:50 INFO - TEST-OK | dromaeo_css | took 1116721ms 09:55:50 INFO - TEST-START | kraken 09:55:50 INFO - operating with platform_type : linux_ 09:55:50 INFO - Initialising browser for kraken test... 09:55:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54747/getInfo.html -profile /tmp/tmpoRRhnX/profile 09:55:50 INFO - TEST-INFO | started process 19505 (/builds/slave/test/build/application/firefox/firefox http://localhost:54747/getInfo.html) 09:55:56 INFO - TEST-INFO | 19505: exit 0 09:55:56 INFO - Browser initialized. 09:55:56 INFO - Running cycle 1/1 for kraken test... 09:55:56 INFO - TEST-INFO | started process 19726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoRRhnX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:07 INFO - PID 19726 | RSS: Main: 171872256 09:56:07 INFO - PID 19726 | 09:56:10 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54747/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:11 INFO - PID 19726 | RSS: Main: 176472064 09:56:11 INFO - PID 19726 | 09:56:14 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54747/tests/kraken/driver.html?testName=audio-dft) 09:56:14 INFO - PID 19726 | RSS: Main: 177168384 09:56:14 INFO - PID 19726 | 09:56:17 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54747/tests/kraken/driver.html?testName=audio-fft) 09:56:18 INFO - PID 19726 | RSS: Main: 172810240 09:56:18 INFO - PID 19726 | 09:56:20 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54747/tests/kraken/driver.html?testName=audio-oscillator) 09:56:20 INFO - PID 19726 | RSS: Main: 172945408 09:56:20 INFO - PID 19726 | 09:56:23 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:23 INFO - PID 19726 | RSS: Main: 172949504 09:56:23 INFO - PID 19726 | 09:56:26 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54747/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:27 INFO - PID 19726 | RSS: Main: 176463872 09:56:27 INFO - PID 19726 | 09:56:30 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54747/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:31 INFO - PID 19726 | RSS: Main: 177979392 09:56:31 INFO - PID 19726 | 09:56:34 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54747/tests/kraken/driver.html?testName=json-parse-financial) 09:56:34 INFO - PID 19726 | RSS: Main: 177143808 09:56:34 INFO - PID 19726 | 09:56:36 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:56:37 INFO - PID 19726 | RSS: Main: 176857088 09:56:37 INFO - PID 19726 | 09:56:38 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:56:39 INFO - PID 19726 | RSS: Main: 177512448 09:56:39 INFO - PID 19726 | 09:56:41 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:56:42 INFO - PID 19726 | RSS: Main: 177451008 09:56:42 INFO - PID 19726 | 09:56:44 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:56:45 INFO - PID 19726 | RSS: Main: 177135616 09:56:45 INFO - PID 19726 | 09:56:48 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:56:49 INFO - PID 19726 | RSS: Main: 177238016 09:56:49 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | Cycle 1(1): loaded http://localhost:54747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54747/tests/kraken/driver.html?testName=ai-astar) 09:56:51 INFO - PID 19726 | RSS: Main: 178851840 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | __start_tp_report 09:56:51 INFO - PID 19726 | _x_x_mozilla_page_load 09:56:51 INFO - PID 19726 | _x_x_mozilla_page_load_details 09:56:51 INFO - PID 19726 | |i|pagename|runs| 09:56:51 INFO - PID 19726 | |0;ai-astar;99;108;108;106;108;116;109;108;109;108 09:56:51 INFO - PID 19726 | |1;audio-beat-detection;151;121;155;181;122;151;154;121;169;126 09:56:51 INFO - PID 19726 | |2;audio-dft;165;162;161;162;162;170;161;178;165;170 09:56:51 INFO - PID 19726 | |3;audio-fft;74;77;75;157;74;75;75;74;102;77 09:56:51 INFO - PID 19726 | |4;audio-oscillator;81;81;141;81;85;82;135;82;81;134 09:56:51 INFO - PID 19726 | |5;imaging-gaussian-blur;113;114;113;114;114;114;114;113;113;115 09:56:51 INFO - PID 19726 | |6;imaging-darkroom;150;150;150;150;150;149;149;155;150;150 09:56:51 INFO - PID 19726 | |7;imaging-desaturate;94;94;95;95;95;94;94;94;94;94 09:56:51 INFO - PID 19726 | |8;json-parse-financial;63;57;61;54;55;54;59;55;54;55 09:56:51 INFO - PID 19726 | |9;json-stringify-tinderbox;53;54;52;53;53;52;53;54;53;53 09:56:51 INFO - PID 19726 | |10;stanford-crypto-aes;77;76;76;77;86;76;85;78;77;77 09:56:51 INFO - PID 19726 | |11;stanford-crypto-ccm;127;128;131;134;136;132;124;135;123;164 09:56:51 INFO - PID 19726 | |12;stanford-crypto-pbkdf2;193;182;178;186;188;183;179;178;178;175 09:56:51 INFO - PID 19726 | |13;stanford-crypto-sha256-iterative;66;69;68;66;67;65;68;73;66;68 09:56:51 INFO - PID 19726 | __end_tp_report 09:56:51 INFO - PID 19726 | __start_cc_report 09:56:51 INFO - PID 19726 | _x_x_mozilla_cycle_collect,933 09:56:51 INFO - PID 19726 | __end_cc_report 09:56:51 INFO - PID 19726 | __startTimestamp1498755411313__endTimestamp 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | ------- Summary: start ------- 09:56:51 INFO - PID 19726 | Number of tests: 14 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#0] ai-astar Cycles:10 Average:107.90 Median:108.50 stddev:4.09 (3.8%) stddev-sans-first:2.80 09:56:51 INFO - PID 19726 | Values: 99.0 108.0 108.0 106.0 108.0 116.0 109.0 108.0 109.0 108.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:152.50 stddev:21.52 (14.1%) stddev-sans-first:22.72 09:56:51 INFO - PID 19726 | Values: 151.0 121.0 155.0 181.0 122.0 151.0 154.0 121.0 169.0 126.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#2] audio-dft Cycles:10 Average:165.60 Median:165.00 stddev:5.52 (3.3%) stddev-sans-first:5.85 09:56:51 INFO - PID 19726 | Values: 165.0 162.0 161.0 162.0 162.0 170.0 161.0 178.0 165.0 170.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#3] audio-fft Cycles:10 Average:86.00 Median:75.00 stddev:26.36 (35.1%) stddev-sans-first:27.60 09:56:51 INFO - PID 19726 | Values: 74.0 77.0 75.0 157.0 74.0 75.0 75.0 74.0 102.0 77.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:81.00 stddev:26.56 (32.8%) stddev-sans-first:27.43 09:56:51 INFO - PID 19726 | Values: 81.0 81.0 141.0 81.0 85.0 82.0 135.0 82.0 81.0 134.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 09:56:51 INFO - PID 19726 | Values: 113.0 114.0 113.0 114.0 114.0 114.0 114.0 113.0 113.0 115.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.00 stddev:1.70 (1.1%) stddev-sans-first:1.80 09:56:51 INFO - PID 19726 | Values: 150.0 150.0 150.0 150.0 150.0 149.0 149.0 155.0 150.0 150.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:56:51 INFO - PID 19726 | Values: 94.0 94.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.00 stddev:3.23 (5.8%) stddev-sans-first:2.50 09:56:51 INFO - PID 19726 | Values: 63.0 57.0 61.0 54.0 55.0 54.0 59.0 55.0 54.0 55.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#9] json-stringify-tinderbox Cycles:10 Average:53.00 Median:53.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 09:56:51 INFO - PID 19726 | Values: 53.0 54.0 52.0 53.0 53.0 52.0 53.0 54.0 53.0 53.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:77.00 stddev:3.75 (4.9%) stddev-sans-first:3.94 09:56:51 INFO - PID 19726 | Values: 77.0 76.0 76.0 77.0 86.0 76.0 85.0 78.0 77.0 77.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#11] stanford-crypto-ccm Cycles:10 Average:133.40 Median:133.00 stddev:11.64 (8.8%) stddev-sans-first:12.12 09:56:51 INFO - PID 19726 | Values: 127.0 128.0 131.0 134.0 136.0 132.0 124.0 135.0 123.0 164.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.00 Median:182.50 stddev:5.58 (3.1%) stddev-sans-first:4.27 09:56:51 INFO - PID 19726 | Values: 193.0 182.0 178.0 186.0 188.0 183.0 179.0 178.0 178.0 175.0 09:56:51 INFO - PID 19726 | 09:56:51 INFO - PID 19726 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:2.27 (3.3%) stddev-sans-first:2.33 09:56:51 INFO - PID 19726 | Values: 66.0 69.0 68.0 66.0 67.0 65.0 68.0 73.0 66.0 68.0 09:56:51 INFO - PID 19726 | -------- Summary: end -------- 09:56:51 INFO - PID 19726 | 09:56:52 INFO - TEST-INFO | 19726: exit 0 09:56:52 INFO - TEST-OK | kraken | took 61415ms 09:56:52 INFO - SUITE-END | took 1178s 09:56:52 INFO - Completed test suite (00:19:38) 09:56:52 INFO - javascript benchmark 09:56:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8209.269066884308, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3021.8640161192743, 3075.8769649214028, 3093.1184612385473, 3103.3793241351727, 3115.26791204067, 5966.403359664031, 6007.909881351783, 6011.879762404755, 6024.87950240996, 6059.757609695613, 6008.429199226078, 6022.849428764283, 6025.819225423234, 6102.450779429851, 6120.1125836753645, 5547.140193270039, 5568.86077848053, 5636.661800291996, 5666.121751128581, 5710.543156547487, 5666.744996475148, 5678.318601767773, 5685.687287199203, 5726.112452569838, 5729.341125770548, 5656.773729050833, 5663.377028526858, 5753.65478071315, 5779.393163717812, 5786.189933409305, 5701.543876489871, 5725.169850371714, 5744.396838331957, 5748.137779333092, 5754.827355179351, 5618.38197798246, 5668.97165514174, 5718.714064296769, 5729.627574207663, 5747.856303592402, 5959.0, 5969.164316995624, 6056.424639659225, 6083.361247069039, 6116.388361163892, 6432.0, 6485.416312531847, 6497.870042599169, 6504.9349506504805, 6513.511486638521, 6646.933530664728, 6670.599764014126, 6672.566283191625, 6682.064510968468, 6690.933090669128, 6196.628202307876, 6197.101420294122, 6199.0, 6205.100260462208, 6212.347703491158, 6811.012403201508, 6818.28408017161, 6821.386075253152, 6826.692798824065, 6826.863462730717, 6271.090691849657, 6381.1066450696935, 6382.648954307558, 6399.552031357854, 6504.804855854332, 6559.0, 6580.473562115019, 6624.337566243337, 6634.86730265392, 6638.904580744123, 6422.197225346832, 6645.468362530986, 6664.566803157779, 6681.198256209286, 6695.330466953363, 6511.0, 6551.770688025897, 6565.0, 6578.046183303394, 6581.80254592363, 5298.470152984671, 5329.413764485904, 5340.172273297645, 5341.893162136736, 5369.838904832861, 4602.631789456836, 4625.0, 4636.374089497875, 4656.301554766745, 4669.229577119737, 5722.084466485342, 5764.682942438122, 5768.048272035151, 5769.596128271065, 5786.855328616818, 6273.247210334789, 6454.419102280724, 6529.640869752209, 6655.667216639149, 6717.899231511531, 5669.461401166965, 5729.140628905698, 5760.3951585083805, 5785.797497087582, 5796.0, 5767.711614419262, 5831.06702927538, 5875.823725288248, 5904.675242861598, 5924.11138329254, 6435.292117867125, 6491.578047426902, 6491.837704057436, 6498.837529061622, 6506.316836732264, 6725.495587830835, 6727.0, 6739.191297044386, 6740.6966686498245, 6740.73037078531, 6195.659238741912, 6422.068800023877, 6423.164988551458, 6431.614103153825, 6434.710438030394, 6314.147590075281, 6363.013732871413, 6392.552521323462, 6454.935450645434, 6476.676166191766, 6712.865742685217, 6741.02255173007, 6744.494162937701, 6746.224184218878, 6766.4248538874745, 6506.41442270198, 6528.6735663215695, 6537.477001839747, 6540.8364790880605, 6546.738130474728, 6513.446357059703, 6513.609183449008, 6519.706613202512, 6522.184726909137, 6535.3464653534265, 6245.96877015612, 6311.526635502264, 6400.295967443671, 6417.614943103429, 6488.675566221765, 6271.43557079865, 6431.485481161402, 6444.323346048597, 6445.774397896239, 6449.5485316027425, 6346.555741098079, 6358.523110766619, 6362.236531616236, 6386.361363863576, 6387.552871298964], "value": 6032.182859441798, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.1978021978022, 817.8078151634361, 841.4572600672566, 859.4241857955169, 867.2801574693003, 36921.63078369219, 37514.99999999996, 37666.623333766656, 37802.8109859451, 37823.81088094559, 36726.449103263476, 37025.444618330745, 37175.25649487009, 37227.0, 37306.62693373065, 6713.966430167842, 6736.562123461984, 6800.353966373186, 6806.285340039274, 6806.523543351967, 6752.324767523258, 6769.0861733665815, 6865.4850886183485, 6868.038474613558, 6874.140732408449, 6797.422219111381, 6809.625470599115, 6840.179178498587, 6840.623765692885, 6841.350071743202, 6807.829804254884, 6811.965940170292, 6814.897776533381, 6817.965910170467, 6871.209810871764, 6143.662098584576, 6188.0, 6206.72069756862, 6207.81376558704, 6213.471854892295, 32972.83513582441, 33269.0, 33304.50043249353, 33443.83278083619, 33510.832445837856, 3294.8187849668257, 3340.1983523954077, 3345.46472564391, 3456.550648415715, 3526.9118272043147, 31102.844485777427, 31231.219219519466, 31304.21739456509, 31346.37307253865, 31491.685083149332, 4701.318308845232, 4864.635152363551, 4923.138450771136, 4959.603231741488, 5038.949610503884, 189524.0523797386, 191719.0, 193353.9999999986, 194690.0, 196577.0, 47216.05567888623, 47511.287330690066, 47816.32642857483, 48381.0, 48553.27170092452, 49460.75269623629, 49665.255021174715, 49844.25233621499, 49890.50109498931, 49952.25071623929, 16324.265408056428, 16399.18004099814, 16415.671686566202, 16415.753763693556, 16499.5875103121, 13661.316934153252, 13736.381862816197, 13749.656258593615, 13828.101173423694, 13850.93074534641, 13603.115797473132, 13750.243736594583, 13816.516421925386, 13852.653683657787, 14018.649533761738, 5475.835724928259, 5556.91664625031, 5603.243562119062, 5616.85957851057, 5794.420557944172, 977.628501169551, 1002.7543251903185, 1003.8745156855393, 1034.2915103154364, 1052.921030922684, 22153.889230553745, 22535.774642253695, 22818.31545053651, 23310.0, 23395.41511462193, 1080.3949788118678, 1100.0759362135843, 1103.9172062095374, 1126.774645070989, 1128.3004537186832, 11706.297622142698, 11738.500615152543, 11974.64076077719, 12062.577809776616, 12360.75278494443, 231586.0, 231952.0, 232341.0, 232355.0, 233820.0, 51915.480845191065, 52189.73905130526, 52251.0, 52509.0, 52855.73572132115, 1421.7298713244656, 1446.9509605536114, 1472.8232612086617, 1481.1927499512792, 1484.5026915983267, 21908.0, 22015.889920550293, 22626.0, 22894.427639308484, 27420.588691170047, 49360.25959610537, 49451.50548494613, 49862.750686246334, 49957.75021124871, 49996.0, 195598.0, 198051.0, 198695.0, 199620.0, 200115.0, 35527.82236088803, 35535.644643553234, 35590.8220458896, 37513.249735005695, 37877.24245515019, 44580.33129503125, 46300.0, 46334.768326158155, 46394.0, 47215.76392118017, 31772.682273177896, 33701.74307294728, 33821.0, 34161.82919085389, 34709.47935780915, 31987.0, 32756.181095472803, 33239.83380083084, 33275.16812079717, 33674.83162584171, 35722.464163038094, 37777.433338499395, 37797.62202377941, 37868.05329866776, 37887.242255155295, 30748.077557673307, 31534.36931261408, 32562.674373255963, 32872.50691239585, 33059.173520662174, 23936.760632393452, 23974.40063998414, 24084.0, 24289.27132186037, 24332.635010475213], "value": 16865.18838530518, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.2171742137139, 567.2483958754651, 576.7260551238162, 578.1414599320009, 592.3987153039665, 15879.0, 21300.57398852024, 21914.12343506258, 22021.559568808636, 22040.0, 18892.716609250838, 19252.903735481304, 22267.55464890701, 23297.417564560896, 24879.75120248797, 6282.842928926779, 6446.806595802133, 6480.254770701359, 6486.643234622117, 6499.870002599945, 6339.080833279195, 6409.455196308296, 6447.451966582823, 6457.031445283198, 6460.515461340395, 3793.886183414502, 4118.670506359499, 6452.7418903243815, 6455.354464553554, 6524.347565243485, 6425.357464253584, 6462.224533056016, 6468.773592924249, 6490.0, 6511.609303441785, 6052.031674932034, 6052.122442245894, 6067.453929146354, 6070.2412198475195, 6075.2405949256345, 27417.177484675492, 27594.72405275962, 27866.58200127, 27887.302817429725, 27984.860075699493, 1920.7887132415563, 2001.099505222641, 2015.1838505405335, 2023.5548179400507, 2034.2778313698573, 17015.319387224496, 17510.124493775387, 17552.122393880254, 17587.824121758746, 17592.736108958376, 2196.2313190383397, 2315.7336906255828, 2323.2217207235594, 2336.497653004612, 2363.9054437822465, 128725.0, 133343.33328333296, 133351.0, 133714.3314283432, 134056.32971835177, 13256.668583285302, 13960.720785584232, 13976.0, 14133.85866141346, 14334.569962900921, 22634.66048009281, 22649.433764156027, 22745.0, 22749.772502275096, 22752.976116074486, 9753.341931871599, 10010.749731256778, 10054.396736195851, 10114.089731924016, 10129.94935025335, 9072.591733372014, 9074.410163339362, 9075.364724469224, 9182.311326650528, 9208.447493150432, 8486.19381158789, 8509.411261695663, 8519.275861551838, 8598.39811213209, 8730.825383492294, 2179.607670619304, 2548.107633343984, 2594.442194928062, 2686.2747058294153, 2782.65216847894, 987.9950600246952, 993.4536005197257, 1001.2740762946805, 1021.6322124035341, 1043.5929987304958, 18205.7269140963, 18461.1692473839, 18795.15421806022, 20954.895225523775, 22614.434639133826, 1023.9846402303972, 1040.292601031306, 1062.8352605346183, 1072.2279958429917, 1093.9507722152523, 10306.845397319046, 10562.366258024544, 10597.470126493645, 10658.0, 10664.360138391721, 482952.75570866466, 496479.0, 508740.0, 513933.0, 515116.0, 109380.453097734, 109741.45129274302, 110035.44982275038, 111553.44223278832, 112266.0, 1234.4321612058554, 1291.225264841088, 1306.8235788168665, 1306.9740253900832, 1308.0712693987186, 15821.683566328838, 18339.35812246565, 19050.142743576296, 19520.0, 20126.597468050266, 47467.28799067948, 50784.492155077976, 51312.230316544534, 51347.2297915539, 52320.0, 61797.69101154465, 62141.0, 62315.0, 62765.68617157068, 63175.6841215791, 12173.147879648339, 12263.93868030654, 12558.811617825922, 12568.74862502763, 12596.748065038832, 15609.297581609082, 15995.680086397973, 16382.672346553241, 16429.932054416426, 16509.17454127313, 13792.724145516833, 13982.0, 14021.859781402056, 14119.152850828572, 14156.150630962175, 12735.108542401942, 12870.806937895752, 13234.867651323364, 13317.733645327233, 13523.729525409633, 13996.090254133449, 14127.64680882986, 14243.928780356031, 14292.0, 14334.784978225127, 12366.44351004189, 12568.057395695176, 12574.371281436075, 12591.433385497858, 12600.810987835006, 13929.373178207208, 13944.442222310994, 14113.647158821112, 14151.363188656742, 14154.929225354219], "value": 11061.128262138436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1305.1907817153365, 1375.6079517337562, 1378.1937566523577, 1387.7155183187447, 1395.4557722488237, 13295.135816171955, 13737.313134343292, 14072.929635351822, 14088.084274522149, 14109.858901410982, 12983.350832458387, 13389.0, 13506.797398039012, 13602.115862468932, 13719.314034298295, 2650.469906018804, 2653.4958357912033, 2697.5953606959015, 2702.540568103427, 2707.241972247774, 2545.567253566898, 2621.2529429112706, 2624.278323461054, 2624.3045592917892, 2624.46198529302, 2564.8204625676212, 2599.3891435512637, 2620.344913771557, 2620.9475810483777, 2620.9606855897177, 2477.950440991179, 2522.066835270952, 2529.519391315653, 2535.1253817432957, 2539.1240022192314, 2484.441000774811, 2489.054159419409, 2533.949321013588, 2595.7663810256977, 2611.8302310349954, 13661.453541858315, 13680.384382702803, 13730.313484325743, 13772.10481318711, 13787.86212137886, 7001.439884809203, 7246.746363877239, 7250.166230883463, 7259.0, 7290.890636640454, 4744.620430365562, 4825.76942879567, 4829.106615276185, 4893.04585605807, 4967.975160124212, 1734.4000954715714, 1763.9823601763946, 1816.0465755478347, 1825.6713791517523, 1858.4053103007043, 21705.13179472835, 21899.562008759895, 21999.450013749625, 22061.779382206292, 23082.884585577132, 6331.113644089831, 6410.45511131559, 6611.735530578723, 6616.768413105518, 6685.39831415175, 15604.219789010504, 15653.60865978337, 15655.060696358254, 15674.529764107096, 15675.0, 7266.164391094986, 7329.706811727578, 7350.816229594196, 7355.007074045043, 7359.227281135511, 6712.597244165366, 6775.254721980578, 6824.317568243136, 6897.241303456616, 6952.965235173893, 4781.593564546983, 4900.289458028566, 4911.533404326583, 4970.627202959792, 5036.345275114285, 1400.4188261871413, 1420.9963950829488, 1447.486142419447, 1449.6303442622275, 1454.6581553334959, 885.6413152673049, 887.4187407248263, 898.8651702244584, 900.9324300677345, 920.6087412849512, 6776.627285499345, 6951.2353641099435, 6952.513324067368, 6988.37104660583, 7026.402755765715, 678.8370791010123, 691.8477934854324, 703.6270776488469, 715.5027256057094, 733.2740586819117, 4415.249407600716, 4701.576858082721, 4982.352294201731, 5055.115354812967, 5273.6572122811895, 17408.39070632522, 18444.89330640166, 18576.25694972213, 18646.44060678182, 18661.0, 17362.565935851704, 18418.631627367376, 18419.815801842076, 18468.353607623667, 18679.06604669761, 9800.460974646463, 10080.445575493419, 10293.279470437148, 10378.0, 10382.325148865451, 7393.223711510429, 7481.214472480311, 7750.186230445947, 7755.418343624364, 7927.207279272257, 15525.689486209987, 15854.7621785671, 16108.67782644364, 16330.0, 16536.834631654012, 15666.138362390178, 16142.435014774896, 16500.42248521296, 16625.50123496297, 16666.33334666648, 9235.445873247627, 9894.455804930798, 10002.399856008371, 10019.849702254327, 10028.64899728506, 11949.522019119138, 11958.10314226454, 11962.700932476757, 12033.4584943676, 12126.514939402678, 9871.950640246752, 10215.489225538544, 10313.896861031593, 10378.377297362182, 10581.4709264538, 10186.949065254626, 11007.174461915229, 11104.888951110386, 11241.0, 11348.659540213806, 8861.468311901306, 9081.0, 9174.174324310845, 9205.263578913804, 9240.214581760625, 6886.311368863074, 6988.860222795617, 7188.173360063451, 7197.676104575202, 7244.492885497964, 6963.268856769966, 7086.39765619928, 7121.928780712334, 7124.002639630559, 7192.31672991065], "value": 6099.3438473058895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.99757001214965, 495.3535635995026, 514.7297668723921, 518.6602775182256, 519.1900635009383, 6163.784267550636, 6213.813585592428, 6220.667249781332, 6225.657588832612, 6298.842528936779, 5952.642841429517, 6175.289841668209, 6179.66011869347, 6190.1643278157435, 6196.535259855506, 4983.177775667011, 4995.825146119886, 4996.725180115092, 5011.599072074226, 5025.296458495814, 4997.025580011881, 5034.194528875362, 5038.118329292378, 5040.420351659551, 5044.319016932704, 4790.473047964721, 4968.527989840959, 4980.97509512452, 4993.0, 5006.824761133361, 4921.950780492203, 4980.0, 4983.230953011701, 4989.226669866177, 4991.376077990251, 4415.712978656377, 4444.488883778375, 4470.977645111786, 4472.597466228055, 4477.306017567283, 5948.167256584082, 6009.519238460913, 6013.428724271187, 6027.4273943975695, 6036.5170786336985, 5703.258576385085, 5724.284464441945, 5724.914126288109, 5738.6556806591325, 5750.367459579485, 6534.575252608565, 6544.018397240405, 6549.901751473732, 6575.178102737158, 6585.769498067592, 4832.299316599108, 4880.2435622478215, 4897.730624815634, 4900.901981960341, 4933.0, 6878.0, 6916.965415172942, 6947.513674042819, 6959.269276725972, 6964.860702785916, 5044.369453818273, 5068.113080210948, 5073.467285934924, 5075.01611235186, 5080.872978175576, 5090.803661139629, 5102.668326558762, 5103.438621751604, 5154.7938082476285, 5156.767945442464, 5698.8290351289525, 5770.105633626795, 5777.768889244383, 5780.479756821906, 5784.363719990798, 6578.835529111715, 6653.833654158685, 6658.4340331071435, 6669.4330981866115, 6695.732170713117, 1749.7550342951997, 1809.1587411853288, 1961.8234358907766, 1967.3704414587194, 1972.2012584903136, 1375.3604573873197, 1463.27567853913, 1482.5626440200167, 1504.9398024078914, 1507.2765072765135, 5613.101903695389, 5648.322201335866, 5727.828165155052, 5729.7708091676695, 5768.625039372428, 6786.151731033621, 6793.116894803644, 6795.52431329812, 6804.251532331439, 6862.451003919675, 5731.140328950691, 5786.88426231473, 5800.1299805029585, 5806.68063256525, 5817.767289308465, 3581.28374325136, 3598.8020658864016, 3604.225091605265, 3606.85572577094, 3619.2761447710564, 6568.540202185896, 6571.342865713486, 6572.11276477679, 6575.605463672195, 6580.894113883607, 6847.246802851682, 6956.826079347956, 6961.791146265519, 6980.895286570705, 6982.93017069833, 6483.675816209171, 6521.315261897527, 6522.478201743849, 6568.737250510022, 6588.308227636029, 6754.189497260164, 6768.187817461936, 6773.322667733188, 6787.62668053242, 6815.965920170367, 6666.0, 6698.698558564974, 6709.194896612437, 6711.0, 6741.0, 6204.472619827366, 6503.837404064936, 6545.672716364067, 6579.703913324008, 6608.8347791305605, 4879.170541007966, 4957.628177886745, 4994.375703037121, 5007.724575148256, 5033.924491132562, 6699.162604674416, 6777.45780337562, 6804.013418054454, 6815.386615204656, 6843.144606924135, 6561.8988132417235, 6571.671416429063, 6574.0, 6582.605043697394, 6587.209534855656, 6490.578112422678, 6561.704723287559, 6561.934380656132, 6580.473562115115, 6585.539012269095, 6481.902771458526, 6498.902516462163, 6517.9348206517325, 6540.500832054828, 6545.967270163619, 6642.269350371552, 6645.0, 6648.434883034996, 6657.234418041793, 6659.500537459807, 6452.677366131581, 6457.160569125984, 6469.0, 6470.838229044311, 6500.797352489804], "value": 5114.437541635561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.8180363927216, 931.1340453378358, 950.6957773512479, 957.8639633617034, 971.1696499492931, 16044.117573533465, 16955.660886782272, 16988.74516882248, 17029.82970170298, 17086.658266834656, 14483.927580362084, 14545.345459454324, 16655.1672416379, 17776.200070996805, 19380.0, 1018.1498448795246, 1035.373598972622, 1039.0700323210722, 1045.0, 1046.3512622174262, 1045.5765415006933, 1048.847917052027, 1051.285126114242, 1051.4479898053544, 1052.8683914510686, 1112.2714621922614, 1115.9888401115966, 1116.7878103160415, 1118.2004866520476, 1121.6803211084843, 1036.7615448446825, 1076.5747529725793, 1094.7317907112767, 1105.287089827061, 1108.9445527723622, 783.5142211828646, 785.8978332816754, 785.9292663660241, 786.7718361675107, 786.9488483248628, 17641.735373969273, 17672.646547069115, 17810.91094544532, 17815.376461823904, 17816.37642682513, 18896.5275868103, 20288.289909853083, 20319.18723251068, 20378.592428151354, 20764.792352076438, 14810.481633142788, 15373.769393459108, 15570.455034073866, 15589.688206235925, 15599.610009749733, 3557.8043207623828, 3568.536090308269, 3576.6065732769375, 3582.247727977128, 3588.6770190682964, 104754.47622761913, 108430.45784771105, 108734.0, 108942.0, 111040.88959110386, 15621.609459763597, 16197.271122799502, 16591.25339359732, 16626.334946602, 16781.07704076263, 28429.71570284312, 28608.427831443256, 29157.0, 29403.558946615816, 29578.0, 8691.608877600524, 8727.869081963776, 8744.825103497895, 8755.387122901464, 8760.211580957744, 4045.453863728359, 4140.37894315855, 4162.771047592351, 4168.895777605584, 4175.164967006611, 4709.3877795887, 4885.413750349981, 4975.90048199034, 4981.900361992813, 5012.04771093491, 1014.6550172941236, 1029.953652085658, 1039.6465201831263, 1058.4654749351682, 1058.9886658241514, 671.1979184874126, 674.4806498995746, 676.4013847744782, 708.8121647763347, 712.0636363182399, 12008.279503229835, 12457.1902826316, 12837.550685725955, 12903.290319032543, 13258.73482530344, 653.6470306034688, 667.8864593019198, 668.1147479589563, 670.0150778355809, 697.6790676288863, 5092.770825312869, 5133.717645529457, 5179.7928082877015, 5311.49540793624, 5380.488853558905, 97028.0, 98223.0, 104626.47686761517, 106082.0, 106787.46606267075, 69022.65488672524, 87433.37699868887, 87732.5613371929, 89737.0, 92062.53968730113, 76655.61672191715, 81004.26431857418, 82638.0, 84342.5782871094, 85885.0, 11551.191416600757, 11806.527738890692, 11893.821592675944, 11965.222260553037, 12218.51125954952, 33844.492332614536, 34295.828520857234, 34389.62441502312, 35051.47422788711, 35987.560497579805, 42494.36258456064, 44945.775271123435, 45176.77411612921, 46066.0, 46474.07051859012, 17681.557961051076, 18028.909855450638, 18176.0, 18196.636067278316, 18529.90735046316, 20347.593048138657, 20400.795992040483, 20604.27885024017, 20627.968601570163, 20840.791592083886, 17797.733034004243, 18294.176762045474, 18489.63020739551, 18910.338138165098, 18995.620087598447, 17651.55871103233, 17752.55618609545, 18050.548736281697, 18140.81859181444, 18174.72737908906, 17140.314387424365, 17547.385841495485, 17682.823171768632, 18017.459476215736, 18258.26966921308, 11492.885071149181, 12328.568500102454, 12537.310447925087, 12861.842434180968, 13084.869151308365, 12000.699982500508, 12576.182548134342, 12598.433070512032, 12647.430865610886, 12762.553310634083], "value": 8718.771692272436, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 108.0, 106.0, 108.0, 116.0, 109.0, 108.0, 109.0, 108.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 155.0, 181.0, 122.0, 151.0, 154.0, 121.0, 169.0, 126.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 161.0, 162.0, 162.0, 170.0, 161.0, 178.0, 165.0, 170.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 157.0, 74.0, 75.0, 75.0, 74.0, 102.0, 77.0], "value": 86.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 141.0, 81.0, 85.0, 82.0, 135.0, 82.0, 81.0, 134.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 150.0, 149.0, 149.0, 155.0, 150.0, 150.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 57.0, 61.0, 54.0, 55.0, 54.0, 59.0, 55.0, 54.0, 55.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 52.0, 53.0, 53.0, 52.0, 53.0, 54.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 77.0, 86.0, 76.0, 85.0, 78.0, 77.0, 77.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 131.0, 134.0, 136.0, 132.0, 124.0, 135.0, 123.0, 164.0], "value": 133.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 182.0, 178.0, 186.0, 188.0, 183.0, 179.0, 178.0, 178.0, 175.0], "value": 182.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 66.0, 67.0, 65.0, 68.0, 73.0, 66.0, 68.0], "value": 67.6, "unit": "score"}]}]} 09:56:52 INFO - Return code: 0 09:56:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:56:52 INFO - # TBPL SUCCESS # 09:56:52 INFO - Running post-action listener: _resource_record_post_action 09:56:52 INFO - [mozharness: 2017-06-29 16:56:52.662145Z] Finished run-tests step (success) 09:56:52 INFO - Running post-run listener: _resource_record_post_run 09:56:52 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 12288; Write bytes: 436109312; Read time: 208; Write time: 284076 09:56:52 INFO - TinderboxPrint: CPU usage
13.1% 09:56:52 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 208 09:56:52 INFO - TinderboxPrint: I/O write bytes / time
436,109,312 / 284,076 09:56:52 INFO - TinderboxPrint: CPU idle
8,293.5 (86.9%) 09:56:52 INFO - TinderboxPrint: CPU user
1,205.7 (12.6%) 09:56:52 INFO - TinderboxPrint: Swap in / out
0 / 0 09:56:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105926656; Read time: 52; Write time: 78664 09:56:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:56:52 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 330182656; Read time: 140; Write time: 205412 09:56:53 INFO - Running post-run listener: _upload_blobber_files 09:56:53 INFO - Blob upload gear active. 09:56:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:56:53 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 09:56:53 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'] 09:56:53 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 09:56:53 INFO - (blobuploader) - INFO - Open directory for files ... 09:56:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:56:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:56:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:55 INFO - (blobuploader) - INFO - Done attempting. 09:56:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:56:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:56:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:55 INFO - (blobuploader) - INFO - Done attempting. 09:56:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:59 INFO - (blobuploader) - INFO - Done attempting. 09:56:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:56:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:57:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:57:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:57:00 INFO - (blobuploader) - INFO - Done attempting. 09:57:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:57:00 INFO - Return code: 0 09:57:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:57:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:57:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb8ee0ea35cef7d202efe3596c0a117787623e1e092ab56fb7d44d440b75e93f78097edc6102438af707d55ee18f22a03f6f481be37b2e51185525f834652ff1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1096bbdbe6e500c5c20de5b7f67586d9b3107a18150b0a6696154fb2b59b9f7d986ea116a42ce13855bdee7b16cb63c0fed3bc0d43e26d626a73db01b7f21959", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10e77580d440c969bd5000319d6da37b495249708f4ebad3ebe3383d2c60d4ba5b26dd517a303af6ea078957de6572ef9c7603d9e94a02deeda878451fcbea0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa849f51b4da51e79b72aa4cb59f96d30e465d865ae57794e3ca35da3ef553a922d7819f18b086a372a704d7528a244c85925efcc561a920f7dd4769c1af5aa9"} 09:57:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:57:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:57:00 INFO - Contents: 09:57:00 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb8ee0ea35cef7d202efe3596c0a117787623e1e092ab56fb7d44d440b75e93f78097edc6102438af707d55ee18f22a03f6f481be37b2e51185525f834652ff1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1096bbdbe6e500c5c20de5b7f67586d9b3107a18150b0a6696154fb2b59b9f7d986ea116a42ce13855bdee7b16cb63c0fed3bc0d43e26d626a73db01b7f21959", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10e77580d440c969bd5000319d6da37b495249708f4ebad3ebe3383d2c60d4ba5b26dd517a303af6ea078957de6572ef9c7603d9e94a02deeda878451fcbea0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa849f51b4da51e79b72aa4cb59f96d30e465d865ae57794e3ca35da3ef553a922d7819f18b086a372a704d7528a244c85925efcc561a920f7dd4769c1af5aa9"} 09:57:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:57:00 INFO - Copying logs to upload dir... 09:57:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:57:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.698189 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-06-29 09:57:00.166650) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-29 09:57:00.188196) ========= 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=6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb8ee0ea35cef7d202efe3596c0a117787623e1e092ab56fb7d44d440b75e93f78097edc6102438af707d55ee18f22a03f6f481be37b2e51185525f834652ff1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1096bbdbe6e500c5c20de5b7f67586d9b3107a18150b0a6696154fb2b59b9f7d986ea116a42ce13855bdee7b16cb63c0fed3bc0d43e26d626a73db01b7f21959", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10e77580d440c969bd5000319d6da37b495249708f4ebad3ebe3383d2c60d4ba5b26dd517a303af6ea078957de6572ef9c7603d9e94a02deeda878451fcbea0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa849f51b4da51e79b72aa4cb59f96d30e465d865ae57794e3ca35da3ef553a922d7819f18b086a372a704d7528a244c85925efcc561a920f7dd4769c1af5aa9"} build_url:https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/Tp3yqa-MSHWH0LwXKp2Dxg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb8ee0ea35cef7d202efe3596c0a117787623e1e092ab56fb7d44d440b75e93f78097edc6102438af707d55ee18f22a03f6f481be37b2e51185525f834652ff1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1096bbdbe6e500c5c20de5b7f67586d9b3107a18150b0a6696154fb2b59b9f7d986ea116a42ce13855bdee7b16cb63c0fed3bc0d43e26d626a73db01b7f21959", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10e77580d440c969bd5000319d6da37b495249708f4ebad3ebe3383d2c60d4ba5b26dd517a303af6ea078957de6572ef9c7603d9e94a02deeda878451fcbea0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa849f51b4da51e79b72aa4cb59f96d30e465d865ae57794e3ca35da3ef553a922d7819f18b086a372a704d7528a244c85925efcc561a920f7dd4769c1af5aa9"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-29 09:57:00.299864) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:57:00.300246) ========= 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=6dbc10da6d83fee962c624be000001a8-1498753572.327838-319267187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004628 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-29 09:57:00.336829) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-29 09:57:00.337242) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-29 09:57:00.337617) ========= ========= Total master_lag: 0.33 =========