builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-002 starttime: 1495873947.16 results: success (0) revision: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.160804) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.161265) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.161658) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.206850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.207112) ========= 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=a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-27 01:32:27-- 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% 25.3M=0s 2017-05-27 01:32:27 (25.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.184016 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.404078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.404386) ========= 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=a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015848 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.455630) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:27.455988) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c --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 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c --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=a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-27 01:32:28,096 truncating revision to first 12 chars 2017-05-27 01:32:28,096 Setting DEBUG logging. 2017-05-27 01:32:28,096 attempt 1/10 2017-05-27 01:32:28,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1c1bf54915bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-27 01:32:28,761 unpacking tar archive at: mozilla-central-1c1bf54915bb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974625 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:28.443508) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:28.443847) ========= script_repo_revision: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:28.444254) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:28.444528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 01:32:28.452992) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2017-05-27 01:32:28.453309) ========= /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=a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:32:29 INFO - MultiFileLogger online at 20170527 01:32:29 in /builds/slave/test 01:32:29 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 01:32:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:32:29 INFO - {'append_to_log': False, 01:32:29 INFO - 'base_work_dir': '/builds/slave/test', 01:32:29 INFO - 'blob_upload_branch': 'Firefox', 01:32:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:32:29 INFO - 'branch': 'Firefox', 01:32:29 INFO - 'buildbot_json_path': 'buildprops.json', 01:32:29 INFO - 'config_files': ('talos/linux_config.py',), 01:32:29 INFO - 'default_actions': ('clobber', 01:32:29 INFO - 'read-buildbot-config', 01:32:29 INFO - 'download-and-extract', 01:32:29 INFO - 'populate-webroot', 01:32:29 INFO - 'create-virtualenv', 01:32:29 INFO - 'install', 01:32:29 INFO - 'run-tests'), 01:32:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:32:29 INFO - 'download_minidump_stackwalk': True, 01:32:29 INFO - 'download_symbols': 'ondemand', 01:32:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:32:29 INFO - 'tooltool.py': '/tools/tooltool.py', 01:32:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:32:29 INFO - '/tools/misc-python/virtualenv.py')}, 01:32:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:32:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:32:29 INFO - 'gecko_profile': False, 01:32:29 INFO - 'gecko_profile_interval': 0, 01:32:29 INFO - 'installer_path': 'installer.exe', 01:32:29 INFO - 'log_level': 'info', 01:32:29 INFO - 'log_name': 'talos', 01:32:29 INFO - 'log_to_console': True, 01:32:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:32:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:32:29 INFO - 'opt_config_files': (), 01:32:29 INFO - 'pip_index': False, 01:32:29 INFO - 'suite': 'dromaeojs-e10s', 01:32:29 INFO - 'system_bits': '32', 01:32:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:32:29 INFO - 'title': 'talos-linux64-ix-002', 01:32:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:32:29 INFO - 'use_talos_json': True, 01:32:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:32:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:32:29 INFO - 'work_dir': 'build'} 01:32:29 INFO - [mozharness: 2017-05-27 08:32:29.193890Z] Running clobber step. 01:32:29 INFO - Running pre-action listener: _resource_record_pre_action 01:32:29 INFO - Running main action method: clobber 01:32:29 INFO - rmtree: /builds/slave/test/build 01:32:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:32:30 INFO - Running post-action listener: _resource_record_post_action 01:32:30 INFO - [mozharness: 2017-05-27 08:32:30.744391Z] Finished clobber step (success) 01:32:30 INFO - [mozharness: 2017-05-27 08:32:30.744490Z] Running read-buildbot-config step. 01:32:30 INFO - Running pre-action listener: _resource_record_pre_action 01:32:30 INFO - Running main action method: read_buildbot_config 01:32:30 INFO - Using buildbot properties: 01:32:30 INFO - { 01:32:30 INFO - "project": "", 01:32:30 INFO - "product": "firefox", 01:32:30 INFO - "who": "archaeopteryx@coole-files.de", 01:32:30 INFO - "installer_path": "public/build/target.tar.bz2", 01:32:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 01:32:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 01:32:30 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 01:32:30 INFO - "stage_platform": "linux64-stylosequential", 01:32:30 INFO - "basedir": "/builds/slave/test", 01:32:30 INFO - "buildnumber": 24, 01:32:30 INFO - "platform": "ubuntu64_hw_styloseq", 01:32:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:32:30 INFO - "slavebuilddir": "test", 01:32:30 INFO - "taskId": "IAZJE63_TAekar1wh9Kh4Q", 01:32:30 INFO - "branch": "mozilla-central", 01:32:30 INFO - "script_repo_revision": "production", 01:32:30 INFO - "revision": "1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c", 01:32:30 INFO - "slavename": "talos-linux64-ix-002", 01:32:30 INFO - "repo_path": "mozilla-central" 01:32:30 INFO - } 01:32:30 INFO - Finding installer, test and symbols from parent task. 01:32:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IAZJE63_TAekar1wh9Kh4Q'}, attempt #1 01:32:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IAZJE63_TAekar1wh9Kh4Q'}, attempt #1 01:32:31 INFO - Task dependencies: OzHpRs25QTOGeaoTcyDY-w 01:32:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w'}, attempt #1 01:32:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2 01:32:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/test_packages.json 01:32:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.crashreporter-symbols.zip 01:32:32 INFO - Running post-action listener: _resource_record_post_action 01:32:32 INFO - [mozharness: 2017-05-27 08:32:32.087684Z] Finished read-buildbot-config step (success) 01:32:32 INFO - [mozharness: 2017-05-27 08:32:32.087903Z] Running download-and-extract step. 01:32:32 INFO - Running pre-action listener: _resource_record_pre_action 01:32:32 INFO - Running main action method: download_and_extract 01:32:32 INFO - mkdir: /builds/slave/test/build/tests 01:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:32:32 INFO - trying https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.test_packages.json 01:32:32 INFO - Downloading https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:32:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:32:32 INFO - Downloaded 986 bytes. 01:32:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:32:32 INFO - Using the following test package requirements: 01:32:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 01:32:32 INFO - u'common': [u'target.common.tests.zip'], 01:32:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:32:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:32:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:32:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:32:32 INFO - u'mozbase': [u'target.common.tests.zip'], 01:32:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:32:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:32:32 INFO - u'web-platform': [u'target.common.tests.zip', 01:32:32 INFO - u'target.web-platform.tests.zip'], 01:32:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:32:32 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:32:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.common.tests.zip 01:32:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:32:32 INFO - Fetch https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.common.tests.zip into memory 01:32:34 INFO - Content-Length response header: 63526719 01:32:34 INFO - Bytes received: 63526719 01:32:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:32:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.common.tests.zip 01:32:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:32:45 INFO - Fetch https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.common.tests.zip into memory 01:32:47 INFO - Content-Length response header: 63526719 01:32:47 INFO - Bytes received: 63526719 01:32:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.talos.tests.zip 01:32:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:32:59 INFO - Fetch https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.talos.tests.zip into memory 01:32:59 INFO - Content-Length response header: 14441221 01:32:59 INFO - Bytes received: 14441221 01:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:00 INFO - trying https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2 01:33:00 INFO - Downloading https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:33:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:33:02 INFO - Downloaded 55753349 bytes. 01:33:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2 01:33:02 INFO - mkdir: /builds/slave/test/properties 01:33:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:33:02 INFO - Writing to file /builds/slave/test/properties/build_url 01:33:02 INFO - Contents: 01:33:02 INFO - build_url:https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2 01:33:02 INFO - Running post-action listener: _resource_record_post_action 01:33:02 INFO - Running post-action listener: set_extra_try_arguments 01:33:02 INFO - [mozharness: 2017-05-27 08:33:02.008517Z] Finished download-and-extract step (success) 01:33:02 INFO - [mozharness: 2017-05-27 08:33:02.008742Z] Running populate-webroot step. 01:33:02 INFO - Running pre-action listener: _resource_record_pre_action 01:33:02 INFO - Running main action method: populate_webroot 01:33:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:33:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:33:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:33:02 INFO - '--tppagecycles', 01:33:02 INFO - '7'], 01:33:02 INFO - 'tests': ['tsvgm']}}, 01:33:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:33:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:33:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:33:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:33:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:33:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:33:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['damp', 'tps']}, 01:33:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'tests': ['damp', 'tps']}, 01:33:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['dromaeo_dom']}, 01:33:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:33:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 01:33:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 01:33:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:33:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['a11yr', 01:33:02 INFO - 'ts_paint', 01:33:02 INFO - 'tpaint', 01:33:02 INFO - 'sessionrestore', 01:33:02 INFO - 'sessionrestore_no_auto_restore', 01:33:02 INFO - 'tabpaint']}, 01:33:02 INFO - 'other-e10s': {'tests': ['a11yr', 01:33:02 INFO - 'ts_paint', 01:33:02 INFO - 'tpaint', 01:33:02 INFO - 'sessionrestore', 01:33:02 INFO - 'sessionrestore_no_auto_restore', 01:33:02 INFO - 'tabpaint']}, 01:33:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:33:02 INFO - 'ts_paint', 01:33:02 INFO - 'tpaint', 01:33:02 INFO - 'sessionrestore', 01:33:02 INFO - 'sessionrestore_no_auto_restore', 01:33:02 INFO - 'tabpaint']}, 01:33:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:33:02 INFO - 'ts_paint', 01:33:02 INFO - 'tpaint', 01:33:02 INFO - 'sessionrestore', 01:33:02 INFO - 'sessionrestore_no_auto_restore', 01:33:02 INFO - 'tabpaint']}, 01:33:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['a11yr', 01:33:02 INFO - 'ts_paint', 01:33:02 INFO - 'tpaint', 01:33:02 INFO - 'sessionrestore', 01:33:02 INFO - 'sessionrestore_no_auto_restore', 01:33:02 INFO - 'tabpaint']}, 01:33:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['a11yr', 01:33:02 INFO - 'ts_paint', 01:33:02 INFO - 'tpaint', 01:33:02 INFO - 'sessionrestore', 01:33:02 INFO - 'sessionrestore_no_auto_restore', 01:33:02 INFO - 'tabpaint']}, 01:33:02 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 01:33:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 01:33:02 INFO - 'bloom_basic_ref']}, 01:33:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['tsvgx', 01:33:02 INFO - 'tsvgr_opacity', 01:33:02 INFO - 'tart', 01:33:02 INFO - 'tscrollx', 01:33:02 INFO - 'cart', 01:33:02 INFO - 'tsvg_static']}, 01:33:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:33:02 INFO - 'tsvgr_opacity', 01:33:02 INFO - 'tart', 01:33:02 INFO - 'tscrollx', 01:33:02 INFO - 'cart', 01:33:02 INFO - 'tsvg_static']}, 01:33:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'talos_options': ['--disable-e10s'], 01:33:02 INFO - 'tests': ['tp5o']}, 01:33:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:33:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'talos_options': ['--disable-e10s', 01:33:02 INFO - '--xperf_path', 01:33:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:33:02 INFO - 'tests': ['tp5n']}, 01:33:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:33:02 INFO - 'talos_options': ['--xperf_path', 01:33:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:33:02 INFO - 'tests': ['tp5n']}}, 01:33:02 INFO - 'talos.zip': {'path': '', 01:33:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:33:02 INFO - Running post-action listener: _resource_record_post_action 01:33:02 INFO - [mozharness: 2017-05-27 08:33:02.031294Z] Finished populate-webroot step (success) 01:33:02 INFO - [mozharness: 2017-05-27 08:33:02.031384Z] Running create-virtualenv step. 01:33:02 INFO - Running pre-action listener: _resource_record_pre_action 01:33:02 INFO - Running main action method: create_virtualenv 01:33:02 INFO - Creating virtualenv /builds/slave/test/build/venv 01:33:02 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 01:33:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:33:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:33:02 INFO - Using real prefix '/usr' 01:33:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:33:02 INFO - Installing distribute.............................................................................................................................................................................................done. 01:33:04 INFO - Installing pip.................done. 01:33:04 INFO - Return code: 0 01:33:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:33:04 INFO - Reading from file tmpfile_stdout 01:33:04 INFO - Output received: 01:33:04 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) 01:33:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:33:04 INFO - Using env: {'DISPLAY': ':0', 01:33:04 INFO - 'HOME': '/home/cltbld', 01:33:04 INFO - 'LANG': 'en_US.UTF-8', 01:33:04 INFO - 'LANGUAGE': 'en_US:en', 01:33:04 INFO - 'LOGNAME': 'cltbld', 01:33:04 INFO - 'MAIL': '/var/mail/cltbld', 01:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:04 INFO - 'MOZ_NO_REMOTE': '1', 01:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:04 INFO - 'NO_EM_RESTART': '1', 01:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:04 INFO - 'PWD': '/builds/slave/test', 01:33:04 INFO - 'SHELL': '/bin/bash', 01:33:04 INFO - 'SHLVL': '1', 01:33:04 INFO - 'TERM': 'linux', 01:33:04 INFO - 'TMOUT': '86400', 01:33:04 INFO - 'USER': 'cltbld', 01:33:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:04 INFO - '_': '/tools/buildbot/bin/python'} 01:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:05 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 01:33:05 INFO - Cleaning up... 01:33:05 INFO - Return code: 0 01:33:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:33: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')]} 01:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33: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')]} 01:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:05 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 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:05 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 01:33:05 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 01:33:05 INFO - Using env: {'DISPLAY': ':0', 01:33:05 INFO - 'HOME': '/home/cltbld', 01:33:05 INFO - 'LANG': 'en_US.UTF-8', 01:33:05 INFO - 'LANGUAGE': 'en_US:en', 01:33:05 INFO - 'LOGNAME': 'cltbld', 01:33:05 INFO - 'MAIL': '/var/mail/cltbld', 01:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:05 INFO - 'MOZ_NO_REMOTE': '1', 01:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:05 INFO - 'NO_EM_RESTART': '1', 01:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:05 INFO - 'PWD': '/builds/slave/test', 01:33:05 INFO - 'SHELL': '/bin/bash', 01:33:05 INFO - 'SHLVL': '1', 01:33:05 INFO - 'TERM': 'linux', 01:33:05 INFO - 'TMOUT': '86400', 01:33:05 INFO - 'USER': 'cltbld', 01:33:05 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:05 INFO - '_': '/tools/buildbot/bin/python'} 01:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:05 INFO - Downloading/unpacking psutil>=3.1.1 01:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:33:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:33:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:33:06 INFO - Installing collected packages: psutil 01:33:06 INFO - Running setup.py install for psutil 01:33:06 INFO - building 'psutil._psutil_linux' extension 01:33:06 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 01:33:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:33:06 INFO - building 'psutil._psutil_posix' extension 01:33:06 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 01:33:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:33:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:33:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:33:06 INFO - Successfully installed psutil 01:33:06 INFO - Cleaning up... 01:33:06 INFO - Return code: 0 01:33:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:33:07 INFO - Using env: {'DISPLAY': ':0', 01:33:07 INFO - 'HOME': '/home/cltbld', 01:33:07 INFO - 'LANG': 'en_US.UTF-8', 01:33:07 INFO - 'LANGUAGE': 'en_US:en', 01:33:07 INFO - 'LOGNAME': 'cltbld', 01:33:07 INFO - 'MAIL': '/var/mail/cltbld', 01:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:07 INFO - 'MOZ_NO_REMOTE': '1', 01:33:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:07 INFO - 'NO_EM_RESTART': '1', 01:33:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:07 INFO - 'PWD': '/builds/slave/test', 01:33:07 INFO - 'SHELL': '/bin/bash', 01:33:07 INFO - 'SHLVL': '1', 01:33:07 INFO - 'TERM': 'linux', 01:33:07 INFO - 'TMOUT': '86400', 01:33:07 INFO - 'USER': 'cltbld', 01:33:07 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:07 INFO - '_': '/tools/buildbot/bin/python'} 01:33:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:33:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:33:08 INFO - Installing collected packages: mozsystemmonitor 01:33:08 INFO - Running setup.py install for mozsystemmonitor 01:33:08 INFO - Successfully installed mozsystemmonitor 01:33:08 INFO - Cleaning up... 01:33:08 INFO - Return code: 0 01:33:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:08 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 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:08 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 01:33:08 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 01:33:08 INFO - Using env: {'DISPLAY': ':0', 01:33:08 INFO - 'HOME': '/home/cltbld', 01:33:08 INFO - 'LANG': 'en_US.UTF-8', 01:33:08 INFO - 'LANGUAGE': 'en_US:en', 01:33:08 INFO - 'LOGNAME': 'cltbld', 01:33:08 INFO - 'MAIL': '/var/mail/cltbld', 01:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:08 INFO - 'MOZ_NO_REMOTE': '1', 01:33:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:08 INFO - 'NO_EM_RESTART': '1', 01:33:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:08 INFO - 'PWD': '/builds/slave/test', 01:33:08 INFO - 'SHELL': '/bin/bash', 01:33:08 INFO - 'SHLVL': '1', 01:33:08 INFO - 'TERM': 'linux', 01:33:08 INFO - 'TMOUT': '86400', 01:33:08 INFO - 'USER': 'cltbld', 01:33:08 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:08 INFO - '_': '/tools/buildbot/bin/python'} 01:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:08 INFO - Downloading/unpacking jsonschema==2.5.1 01:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:33:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:09 INFO - Downloading functools32-3.2.3-2.tar.gz 01:33:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:33:09 INFO - warning: no files found matching '*.txt' 01:33:09 INFO - no previously-included directories found matching 'build' 01:33:09 INFO - no previously-included directories found matching 'dist' 01:33:09 INFO - no previously-included directories found matching '.git*' 01:33:09 INFO - Installing collected packages: jsonschema, functools32 01:33:10 INFO - Running setup.py install for functools32 01:33:10 INFO - warning: no files found matching '*.txt' 01:33:10 INFO - no previously-included directories found matching 'build' 01:33:10 INFO - no previously-included directories found matching 'dist' 01:33:10 INFO - no previously-included directories found matching '.git*' 01:33:10 INFO - Successfully installed jsonschema functools32 01:33:10 INFO - Cleaning up... 01:33:10 INFO - Return code: 0 01:33:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:33:10 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')]} 01:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:10 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')]} 01:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:10 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 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:10 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 01:33:10 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 01:33:10 INFO - Using env: {'DISPLAY': ':0', 01:33:10 INFO - 'HOME': '/home/cltbld', 01:33:10 INFO - 'LANG': 'en_US.UTF-8', 01:33:10 INFO - 'LANGUAGE': 'en_US:en', 01:33:10 INFO - 'LOGNAME': 'cltbld', 01:33:10 INFO - 'MAIL': '/var/mail/cltbld', 01:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:10 INFO - 'MOZ_NO_REMOTE': '1', 01:33:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:10 INFO - 'NO_EM_RESTART': '1', 01:33:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:10 INFO - 'PWD': '/builds/slave/test', 01:33:10 INFO - 'SHELL': '/bin/bash', 01:33:10 INFO - 'SHLVL': '1', 01:33:10 INFO - 'TERM': 'linux', 01:33:10 INFO - 'TMOUT': '86400', 01:33:10 INFO - 'USER': 'cltbld', 01:33:10 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:10 INFO - '_': '/tools/buildbot/bin/python'} 01:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:33:10 INFO - Cleaning up... 01:33:10 INFO - Return code: 0 01:33:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:33:10 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')]} 01:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:10 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')]} 01:33:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:10 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 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:10 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 01:33:10 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 01:33:10 INFO - Using env: {'DISPLAY': ':0', 01:33:10 INFO - 'HOME': '/home/cltbld', 01:33:10 INFO - 'LANG': 'en_US.UTF-8', 01:33:10 INFO - 'LANGUAGE': 'en_US:en', 01:33:10 INFO - 'LOGNAME': 'cltbld', 01:33:10 INFO - 'MAIL': '/var/mail/cltbld', 01:33:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:10 INFO - 'MOZ_NO_REMOTE': '1', 01:33:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:10 INFO - 'NO_EM_RESTART': '1', 01:33:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:10 INFO - 'PWD': '/builds/slave/test', 01:33:10 INFO - 'SHELL': '/bin/bash', 01:33:10 INFO - 'SHLVL': '1', 01:33:10 INFO - 'TERM': 'linux', 01:33:10 INFO - 'TMOUT': '86400', 01:33:10 INFO - 'USER': 'cltbld', 01:33:10 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:10 INFO - '_': '/tools/buildbot/bin/python'} 01:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:10 INFO - Downloading/unpacking blobuploader==1.2.4 01:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:11 INFO - Downloading blobuploader-1.2.4.tar.gz 01:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:33:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:33:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:12 INFO - Downloading docopt-0.6.1.tar.gz 01:33:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:33:12 INFO - Installing collected packages: blobuploader, requests, docopt 01:33:12 INFO - Running setup.py install for blobuploader 01:33:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:33:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:33:12 INFO - Running setup.py install for requests 01:33:12 INFO - Running setup.py install for docopt 01:33:12 INFO - Successfully installed blobuploader requests docopt 01:33:12 INFO - Cleaning up... 01:33:12 INFO - Return code: 0 01:33:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:33:12 INFO - Using env: {'DISPLAY': ':0', 01:33:12 INFO - 'HOME': '/home/cltbld', 01:33:12 INFO - 'LANG': 'en_US.UTF-8', 01:33:12 INFO - 'LANGUAGE': 'en_US:en', 01:33:12 INFO - 'LOGNAME': 'cltbld', 01:33:12 INFO - 'MAIL': '/var/mail/cltbld', 01:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:12 INFO - 'MOZ_NO_REMOTE': '1', 01:33:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:12 INFO - 'NO_EM_RESTART': '1', 01:33:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:12 INFO - 'PWD': '/builds/slave/test', 01:33:12 INFO - 'SHELL': '/bin/bash', 01:33:12 INFO - 'SHLVL': '1', 01:33:12 INFO - 'TERM': 'linux', 01:33:12 INFO - 'TMOUT': '86400', 01:33:12 INFO - 'USER': 'cltbld', 01:33:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:12 INFO - '_': '/tools/buildbot/bin/python'} 01:33:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:33:13 INFO - Running setup.py (path:/tmp/pip-EdPyK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:33:13 INFO - Running setup.py (path:/tmp/pip-86uOsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:33:13 INFO - Running setup.py (path:/tmp/pip-gTOrov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:33:13 INFO - Running setup.py (path:/tmp/pip-4kSKGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:33:13 INFO - Running setup.py (path:/tmp/pip-Mv63Xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:33:13 INFO - Running setup.py (path:/tmp/pip-SDineO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:33:13 INFO - Running setup.py (path:/tmp/pip-v_PkWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:33:13 INFO - Running setup.py (path:/tmp/pip-uPYKuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:33:13 INFO - Running setup.py (path:/tmp/pip-Cw7g1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:33:13 INFO - Running setup.py (path:/tmp/pip-bl7F4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:33:13 INFO - Running setup.py (path:/tmp/pip-aRuKQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:33:13 INFO - Running setup.py (path:/tmp/pip-OOMBOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:33:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:33:13 INFO - Running setup.py (path:/tmp/pip-JCHOjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:33:14 INFO - Running setup.py (path:/tmp/pip-S56gvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:14 INFO - Running setup.py (path:/tmp/pip-LoY9j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:33:14 INFO - Running setup.py (path:/tmp/pip-l4sn54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:33:14 INFO - Running setup.py (path:/tmp/pip-Il5e9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:33:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:33:14 INFO - Running setup.py install for manifestparser 01:33:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:33:14 INFO - Running setup.py install for mozcrash 01:33:14 INFO - Running setup.py install for mozdebug 01:33:14 INFO - Running setup.py install for mozdevice 01:33:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:33:14 INFO - Running setup.py install for mozfile 01:33:14 INFO - Running setup.py install for mozhttpd 01:33:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:33:14 INFO - Running setup.py install for mozinfo 01:33:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:33:14 INFO - Running setup.py install for mozInstall 01:33:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Running setup.py install for mozleak 01:33:15 INFO - Running setup.py install for mozlog 01:33:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Running setup.py install for moznetwork 01:33:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Running setup.py install for mozprocess 01:33:15 INFO - Running setup.py install for mozprofile 01:33:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Running setup.py install for mozrunner 01:33:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:33:15 INFO - Running setup.py install for mozscreenshot 01:33:15 INFO - Running setup.py install for moztest 01:33:15 INFO - Running setup.py install for mozversion 01:33:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:33:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:33:16 INFO - Cleaning up... 01:33:16 INFO - Return code: 0 01:33:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:16 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 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:16 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 01:33:16 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 01:33:16 INFO - Using env: {'DISPLAY': ':0', 01:33:16 INFO - 'HOME': '/home/cltbld', 01:33:16 INFO - 'LANG': 'en_US.UTF-8', 01:33:16 INFO - 'LANGUAGE': 'en_US:en', 01:33:16 INFO - 'LOGNAME': 'cltbld', 01:33:16 INFO - 'MAIL': '/var/mail/cltbld', 01:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:16 INFO - 'MOZ_NO_REMOTE': '1', 01:33:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:16 INFO - 'NO_EM_RESTART': '1', 01:33:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:16 INFO - 'PWD': '/builds/slave/test', 01:33:16 INFO - 'SHELL': '/bin/bash', 01:33:16 INFO - 'SHLVL': '1', 01:33:16 INFO - 'TERM': 'linux', 01:33:16 INFO - 'TMOUT': '86400', 01:33:16 INFO - 'USER': 'cltbld', 01:33:16 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:16 INFO - '_': '/tools/buildbot/bin/python'} 01:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:33:16 INFO - Running setup.py (path:/tmp/pip-kYeDPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:33:16 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:33:16 INFO - Running setup.py (path:/tmp/pip-piWaYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:33:16 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:33:16 INFO - Running setup.py (path:/tmp/pip-k0EseJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:33:16 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:33:16 INFO - Running setup.py (path:/tmp/pip-h_CyyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:33:16 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:33:16 INFO - Running setup.py (path:/tmp/pip-0dOvh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:33:16 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:33:16 INFO - Running setup.py (path:/tmp/pip-_dP6iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:33:16 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:33:16 INFO - Running setup.py (path:/tmp/pip-ZbabH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:33:16 INFO - Running setup.py (path:/tmp/pip-KynHOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:33:16 INFO - Running setup.py (path:/tmp/pip-DrI5RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:33:16 INFO - Running setup.py (path:/tmp/pip-bLwCdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:33:17 INFO - Running setup.py (path:/tmp/pip-_aKErU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:33:17 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)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:33:17 INFO - Running setup.py (path:/tmp/pip-ZBSdwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:33:17 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)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:33:17 INFO - Running setup.py (path:/tmp/pip-tyMriq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:33:17 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)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:33:17 INFO - Running setup.py (path:/tmp/pip-iQSlNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:33:17 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)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:17 INFO - Running setup.py (path:/tmp/pip-6ycYNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:17 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)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:33:17 INFO - Running setup.py (path:/tmp/pip-Up9VMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:33:17 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)) 01:33:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:33:17 INFO - Running setup.py (path:/tmp/pip-MZM8iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:33:17 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)) 01:33:17 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)) 01:33:17 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)) 01:33:17 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)) 01:33:17 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)) 01:33:17 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)) 01:33:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:18 INFO - Downloading blessings-1.6.tar.gz 01:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:33:18 INFO - Installing collected packages: blessings 01:33:18 INFO - Running setup.py install for blessings 01:33:18 INFO - Successfully installed blessings 01:33:18 INFO - Cleaning up... 01:33:18 INFO - Return code: 0 01:33:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:33:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:33:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:33:19 INFO - Reading from file tmpfile_stdout 01:33:19 INFO - Current package versions: 01:33:19 INFO - argparse == 1.2.1 01:33:19 INFO - blessings == 1.6 01:33:19 INFO - blobuploader == 1.2.4 01:33:19 INFO - docopt == 0.6.1 01:33:19 INFO - functools32 == 3.2.3-2 01:33:19 INFO - jsonschema == 2.5.1 01:33:19 INFO - manifestparser == 1.1 01:33:19 INFO - mozInstall == 1.12 01:33:19 INFO - mozcrash == 1.0 01:33:19 INFO - mozdebug == 0.1 01:33:19 INFO - mozdevice == 0.51 01:33:19 INFO - mozfile == 1.2 01:33:19 INFO - mozhttpd == 0.7 01:33:19 INFO - mozinfo == 0.9 01:33:19 INFO - mozleak == 0.1 01:33:19 INFO - mozlog == 3.4 01:33:19 INFO - moznetwork == 0.27 01:33:19 INFO - mozprocess == 0.25 01:33:19 INFO - mozprofile == 0.28 01:33:19 INFO - mozrunner == 6.13 01:33:19 INFO - mozscreenshot == 0.1 01:33:19 INFO - mozsystemmonitor == 0.3 01:33:19 INFO - moztest == 0.8 01:33:19 INFO - mozversion == 1.4 01:33:19 INFO - psutil == 3.1.1 01:33:19 INFO - requests == 1.2.3 01:33:19 INFO - wsgiref == 0.1.2 01:33:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:19 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 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:19 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 01:33:19 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 01:33:19 INFO - Using env: {'DISPLAY': ':0', 01:33:19 INFO - 'HOME': '/home/cltbld', 01:33:19 INFO - 'LANG': 'en_US.UTF-8', 01:33:19 INFO - 'LANGUAGE': 'en_US:en', 01:33:19 INFO - 'LOGNAME': 'cltbld', 01:33:19 INFO - 'MAIL': '/var/mail/cltbld', 01:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:19 INFO - 'MOZ_NO_REMOTE': '1', 01:33:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:19 INFO - 'NO_EM_RESTART': '1', 01:33:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:19 INFO - 'PWD': '/builds/slave/test', 01:33:19 INFO - 'SHELL': '/bin/bash', 01:33:19 INFO - 'SHLVL': '1', 01:33:19 INFO - 'TERM': 'linux', 01:33:19 INFO - 'TMOUT': '86400', 01:33:19 INFO - 'USER': 'cltbld', 01:33:19 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:19 INFO - '_': '/tools/buildbot/bin/python'} 01:33:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 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)) 01:33:19 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:20 INFO - Downloading marionette_driver-1.4.0.tar.gz 01:33:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 01:33:20 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)) 01:33:20 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)) 01:33:20 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)) 01:33:20 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)) 01:33:20 INFO - Installing collected packages: marionette-driver 01:33:20 INFO - Running setup.py install for marionette-driver 01:33:20 INFO - Successfully installed marionette-driver 01:33:20 INFO - Cleaning up... 01:33:20 INFO - Return code: 0 01:33:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e35c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, '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': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', '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 01:33:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:33:20 INFO - Using env: {'DISPLAY': ':0', 01:33:20 INFO - 'HOME': '/home/cltbld', 01:33:20 INFO - 'LANG': 'en_US.UTF-8', 01:33:20 INFO - 'LANGUAGE': 'en_US:en', 01:33:20 INFO - 'LOGNAME': 'cltbld', 01:33:20 INFO - 'MAIL': '/var/mail/cltbld', 01:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:20 INFO - 'MOZ_NO_REMOTE': '1', 01:33:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:20 INFO - 'NO_EM_RESTART': '1', 01:33:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:20 INFO - 'PWD': '/builds/slave/test', 01:33:20 INFO - 'SHELL': '/bin/bash', 01:33:20 INFO - 'SHLVL': '1', 01:33:20 INFO - 'TERM': 'linux', 01:33:20 INFO - 'TMOUT': '86400', 01:33:20 INFO - 'USER': 'cltbld', 01:33:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:20 INFO - '_': '/tools/buildbot/bin/python'} 01:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:33:20 INFO - Cleaning up... 01:33:20 INFO - Return code: 0 01:33:20 INFO - Running post-action listener: _resource_record_post_action 01:33:20 INFO - Running post-action listener: _start_resource_monitoring 01:33:20 INFO - Starting resource monitoring. 01:33:20 INFO - [mozharness: 2017-05-27 08:33:20.852845Z] Finished create-virtualenv step (success) 01:33:20 INFO - [mozharness: 2017-05-27 08:33:20.853351Z] Running install step. 01:33:20 INFO - Running pre-action listener: _resource_record_pre_action 01:33:20 INFO - Running main action method: install 01:33:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:33:21 INFO - Reading from file tmpfile_stdout 01:33:21 INFO - Detecting whether we're running mozinstall >=1.0... 01:33:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:33:21 INFO - Reading from file tmpfile_stdout 01:33:21 INFO - Output received: 01:33:21 INFO - Usage: mozinstall [options] installer 01:33:21 INFO - Options: 01:33:21 INFO - -h, --help show this help message and exit 01:33:21 INFO - -d DEST, --destination=DEST 01:33:21 INFO - Directory to install application into. [default: 01:33:21 INFO - "/builds/slave/test"] 01:33:21 INFO - --app=APP Application being installed. [default: firefox] 01:33:21 INFO - mkdir: /builds/slave/test/build/application 01:33:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:33:37 INFO - Reading from file tmpfile_stdout 01:33:37 INFO - Output received: 01:33:37 INFO - /builds/slave/test/build/application/firefox/firefox 01:33:37 INFO - Running post-action listener: _resource_record_post_action 01:33:37 INFO - [mozharness: 2017-05-27 08:33:37.149096Z] Finished install step (success) 01:33:37 INFO - [mozharness: 2017-05-27 08:33:37.149372Z] Running run-tests step. 01:33:37 INFO - Running pre-action listener: _resource_record_pre_action 01:33:37 INFO - Running main action method: run_tests 01:33:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:33:37 INFO - Python 2.7.3 01:33:37 INFO - Return code: 0 01:33:37 INFO - grabbing minidump binary from tooltool 01:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e07a0>, '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 0x10e16a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e1b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:33:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:33:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:33:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:33:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:33:37 INFO - Return code: 0 01:33:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:33:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:33:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:33:37 INFO - ENV: RUST_BACKTRACE is now 1 01:33:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:33:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:33:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 01:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 01:33:37 INFO - Using env: {'DISPLAY': ':0', 01:33:37 INFO - 'HOME': '/home/cltbld', 01:33:37 INFO - 'LANG': 'en_US.UTF-8', 01:33:37 INFO - 'LANGUAGE': 'en_US:en', 01:33:37 INFO - 'LOGNAME': 'cltbld', 01:33:37 INFO - 'MAIL': '/var/mail/cltbld', 01:33:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:33:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:37 INFO - 'MOZ_NO_REMOTE': '1', 01:33:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:33:37 INFO - 'NO_EM_RESTART': '1', 01:33:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:37 INFO - 'PWD': '/builds/slave/test', 01:33:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:33:37 INFO - 'RUST_BACKTRACE': '1', 01:33:37 INFO - 'SHELL': '/bin/bash', 01:33:37 INFO - 'SHLVL': '1', 01:33:37 INFO - 'TERM': 'linux', 01:33:37 INFO - 'TMOUT': '86400', 01:33:37 INFO - 'USER': 'cltbld', 01:33:37 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905', 01:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:37 INFO - '_': '/tools/buildbot/bin/python'} 01:33:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 01:33:37 INFO - mozversion application_buildid: 20170527075057 01:33:37 INFO - mozversion application_changeset: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c 01:33:37 INFO - mozversion application_display_name: Nightly 01:33:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:33:37 INFO - mozversion application_name: Firefox 01:33:37 INFO - mozversion application_remotingname: firefox 01:33:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:33:37 INFO - mozversion application_vendor: Mozilla 01:33:37 INFO - mozversion application_version: 55.0a1 01:33:37 INFO - mozversion platform_buildid: 20170527075057 01:33:37 INFO - mozversion platform_changeset: 1c1bf54915bbf8cda82dbf7985e87b1c0ff30e4c 01:33:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:33:37 INFO - mozversion platform_version: 55.0a1 01:33:37 INFO - using testdate: 1495874017 01:33:37 INFO - actual date: 1495874017 01:33:37 INFO - starting webserver on 'localhost:60784' 01:33:37 INFO - SUITE-START | Running 2 tests 01:33:37 INFO - TEST-START | dromaeo_css 01:33:37 INFO - operating with platform_type : linux_ 01:33:37 INFO - Initialising browser for dromaeo_css test... 01:33:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60784/getInfo.html -profile /tmp/tmpS6sB8D/profile 01:33:37 INFO - TEST-INFO | started process 6228 (/builds/slave/test/build/application/firefox/firefox http://localhost:60784/getInfo.html) 01:34:12 INFO - TEST-INFO | 6228: killed by SIGTERM 01:34:12 INFO - Browser initialized. 01:34:12 INFO - Running cycle 1/1 for dromaeo_css test... 01:34:12 INFO - TEST-INFO | started process 6449 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS6sB8D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:34:24 INFO - PID 6449 | RSS: Main: 172949504 01:34:24 INFO - PID 6449 | 01:37:28 INFO - PID 6449 | Cycle 1(1): loaded http://localhost:60784/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60784/tests/dromaeo/cssquery-ext.html) 01:37:30 INFO - PID 6449 | RSS: Main: 171712512 01:37:30 INFO - PID 6449 | 01:40:32 INFO - PID 6449 | Cycle 1(1): loaded http://localhost:60784/tests/dromaeo/cssquery-ext.html (next: http://localhost:60784/tests/dromaeo/cssquery-jquery.html) 01:40:32 INFO - PID 6449 | RSS: Main: 177709056 01:40:32 INFO - PID 6449 | 01:43:34 INFO - PID 6449 | Cycle 1(1): loaded http://localhost:60784/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60784/tests/dromaeo/cssquery-mootools.html) 01:43:35 INFO - PID 6449 | RSS: Main: 175656960 01:43:35 INFO - PID 6449 | 01:46:36 INFO - PID 6449 | Cycle 1(1): loaded http://localhost:60784/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60784/tests/dromaeo/cssquery-prototype.html) 01:46:37 INFO - PID 6449 | RSS: Main: 179417088 01:46:37 INFO - PID 6449 | 01:49:40 INFO - PID 6449 | Cycle 1(1): loaded http://localhost:60784/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60784/tests/dromaeo/cssquery-yui.html) 01:49:41 INFO - PID 6449 | RSS: Main: 177750016 01:49:41 INFO - PID 6449 | 01:52:42 INFO - PID 6449 | Cycle 1(1): loaded http://localhost:60784/tests/dromaeo/cssquery-yui.html (next: http://localhost:60784/tests/dromaeo/cssquery-dojo.html) 01:52:43 INFO - PID 6449 | RSS: Main: 175579136 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | __start_tp_report 01:52:43 INFO - PID 6449 | _x_x_mozilla_page_load 01:52:43 INFO - PID 6449 | _x_x_mozilla_page_load_details 01:52:43 INFO - PID 6449 | |i|pagename|runs| 01:52:43 INFO - PID 6449 | |0;dojo.html;2753.8760755765993;2754.834709917404;2770.8198967067146;2772.639556857608;2784.2204182828814;5242.737863106844;5246.895062098761;5312.256284120226;5349.144136938091;5368.70472124033;5342.946570534294;5357.008953343634;5389.973050134754;5390.649607775492;5434.0218760623075;4826.517348265163;4858.611311095113;4862.367892174012;4870.780814863339;4879.121758083544;25.375782390749198;4860.125177468054;4887.682300650464;4888.633352498559;4946.72792996385;4453.109378124355;4805.783739731703;4815.085133824562;4833.975830120827;4852.344933433977;4803.807847686088;4846;4857.125717370872;4873.439554451248;4875.756212189376;4625.629949604024;4812.711237325771;4819.397575303087;4820.276958456225;4855.878603034917;5346.8395948121615;5348.037353276409;5363.570914326844;5392.379876314235;5398.4871437213405;5811.244538210048;5820.156077368758;5822.184894114827;5837;5841.678707671076;5888.381719919433;5889.852753681193;5889.882202355929;5905.940940590582;5909.202257695242;5329.600279979017;5386.272853164851;5457;5485.50630443262;5507.752151153208;6043.939560604425;6055.939440605537;6093.086037094383;6104.877902441926;6111.96944015277;5760.934227167903;5761.884762304814;5802.158686990363;5803.6807975560905;5807.68057756819;5768.913466298009;5769.509591684669;5844.824655260349;5858.882822343529;5886.175935369051;5801.53587712982;5814.215080964016;5817.970910145506;5838.7080645967535;5867.589268751147;5791.0734282514595;5792.333881603585;5796.710164491758;5803.506701930299;5817.214676018683;4782.545658162469;4824.107540105092;4829.058333624979;4834.564889159992;4880;4135.91728165435;4185.811638476276;4196.433481480022;4255.106427650167;4264.040590867092;4923.212286034217;4961.181405068195;4967.155583550805;4974.751262436864;5002.974985125051;5791.652500849962;5880;5913.231279933581;5954.732037058343;5958.1062840573395;4868.3427737255015;4871.975640121847;4942.456329803718;4970.801167953314;4970.975145124251;5154.30416893722;5167.819126330561;5172.431032586413;5178.249153872667;5189.117849965515;5421.5391691706645;5424;5433.185022246695;5439.102548079522;5466.835994920159;5691.7438715257085;5759.366469688247;5839.5036421904615;5896.292444906639;5896.616719913192;5359.866003349947;5366.651167674245;5392.245085687929;5398.811041613525;5401.810936617199;5697.572682048946;5699.5725320600295;5719.742611582572;5723.8569035774435;5774.133879918046;5683.175939488834;5769.1634712967225;5796.246487956539;5805.129230615442;5916.852078698067;5684.516816070681;5685.630434021776;5691.573132015198;5714.314282286152;5750.539956803529;5620;5654.293213348332;5656.095024796013;5674.858128546654;5678.68767217807;5530.861728456821;5651.8304450866535;5655.971720141373;5677.687727175045;5704.144378343116;5725.341585717696;5757.510611598061;5764.769409223584;5769.365369809401;5775.249217601685;5592.608517403905;5595.188697638901;5603.691796951207;5604.299462567179;5607.80372686954 01:52:43 INFO - PID 6449 | |1;ext.html;675.7263308360116;702.181958018908;727.7343769524125;1077.8975997280256;1089.8637670291214;31423.685763142363;31867.52198717021;32055;32073.35853282933;32118.197045073885;33587.496187557146;33861.153471163234;34098.488522672116;34116.48825267623;34163.65836341636;6528.738850445977;6599.505037122211;6762.154730658668;6862.279460656634;6881.759138430156;6578.440832529241;6693.364130407604;6699.330066993286;6702.36327548882;6706.0276259942275;6669.666516674171;6743.15710536183;6766.25571187169;6789.422899053587;6800.65996700163;6694.397504224618;6708.765193218214;6718.798436046926;6722.226943901465;6726.663666816639;6489.513286503484;6503.089567460559;6535.313792051861;6549.246836613803;6566.868662626768;30151.246218844484;30231.395372092436;30278.394432111454;30345;30415.087547373616;2993.4462124506817;3022.0631604202767;3023.3499797543413;3026.6065411496584;3032.969670303291;22956.19653312126;22974.425639358982;22974.65538016931;22992.310230693107;22998.08007679691;2958.1569252763074;2958.866850991689;3032.438998785232;3035.468792961223;3042.1481985044;128909.35545322308;129358.3532082343;129406;145012;150330.24834875864;38445;38600;38605.613943860204;38691.41962870559;38723.127687231616;40063.39904901429;40198;40230;40236.59763402329;40381.59618403837;11305.660830175108;11408.25846320003;11413.60052398162;11422.543098276143;11469.88530114705;9123.996360400351;9133.95433022844;9144.268458523302;9147.313951453667;9191.034941331198;12488.313142777235;12492.81260781089;12521.186122901981;12595.87404125947;12741.55404560836;5161.81933632321;5208.921866172011;5264.842054738364;5331.360236771612;5378.596605254622;855.8502262104107;884.5754038061766;892.4466830565091;895.2882458445551;920.2132176988579;20099.465794929027;20813.687794683094;20994.160233590792;21007.634503757206;21214.893925530272;957.1768279279814;960.2510042167121;961.0437614573544;963.8361478548663;973.9951300243454;11044.502997365138;11084;11084.722881927855;11116.722081948015;11117.777644447066;170704;171068;171081;171181;171630.14184928997;39429.40855887164;39683.20633587312;39704.404433933516;39708.20583588312;39770;1196.5315357457669;1224.0880543994604;1230.655416483368;1232.8027515597641;1235.8506588872435;19636.607267854848;19828.70256946118;20147.496312592302;20369.79630203679;20415.69376459384;28686.713132868405;28735.71264287414;28852.278693032004;28872.278193045342;29010.854945725136;136707;137252.31373843466;137363;138512.3074384662;139231;28461.146165615064;28508.144755657362;28718.856405717837;29647.555286671148;29671;34858;34932.12669683278;35088.298234034664;35240.82379588085;35272.82363588166;25986.48027039411;27046.864765676044;27077.86461067682;27636.861815690794;27702.722972770014;26486.7351326492;26679.86660066687;27240.591391129547;27334.453310933273;27946.72053279441;30503.084907452812;30652;30802.537961930142;30816.84591577028;30894.69105308918;25946.22161335163;26432.867835660698;26753.866230668722;27065.72934270711;27484.312892177055;21241.78758212398;22114.44713882166;22130.446738831015;22162.33512994613;22287.77712222857 01:52:43 INFO - PID 6449 | |2;jquery.html;403.58966731122024;403.81626360006203;413.1324219139806;425.82753984636224;433.3932494507689;16817.15914204291;17415.303387864486;17437.128143592832;18615.81384186161;19116.61766764664;19002.42992710221;19409.991451325033;19529;19588.216471341166;19616.215351385927;5306.151015837467;5359.330083739543;5415.783368665248;5451.945480545203;5548.029094908375;5383.81156659517;5455.427180146087;5461.317335333083;5471;5477.780888764445;5244.423113457536;5339.919901201485;5427.62006659534;5446.645968012086;5461.399246082948;5353.652012619167;5381.919271210915;5393.514583687487;5396.784128634869;5434.3207099112615;4786.7958722122985;4829.082474329866;4861.756912154378;4863.975680121612;4891.3396691446915;22040.559188816133;22286.331410057563;22350;22471.550568988692;22474.213402530833;1611.6534944986884;1646.7118254305449;1653.3221379234576;1658.461000174948;1659.444086231114;14216.502422415166;14328.28358582077;14787.704245915129;14897.627559311104;15014.62463438412;1953.238237087537;2013.5570174561574;2026.827719643832;2029.4926268432891;2042.5302180498568;92276.53861730716;95690;95911;99188;100826.99999999927;10620.628278010232;10879.619213327496;11003.61487347955;11207.66377008691;11279;15275.923620381827;15401.22993850303;15439.922800386152;15448;15469.226538673021;7229.139747053542;7405.7778266652085;7418.18399976013;7577.355924746348;7579.924200758032;6687.866242675216;6805.93194068053;6919.411849992706;6921.861562768717;6927.134108236471;6953.61755103464;7034.92965070353;7039.894401583981;7101.183363913216;7163.498555101197;1996.6805311150147;1997.8401727862026;2048.313814872005;2074.222166687492;2154.342925407734;832.3590835056972;868.3270465389247;869.6477926439802;872.5855218771032;895.7536677413764;17060.317587296617;17326;17415.73876391855;17441.12794360277;18172;886.6276164011131;891.7057371067532;892.7857314244535;896.3501461440534;915.1031988651159;9470.668526601536;9721.319507634398;9741.318107732392;9785.168260697921;9922.454265015349;337947;342190.89045547624;343759;344706;344892;76583.61708191535;78012.60993695109;78387.21612783913;78562.6071869637;78736;1041.7656027393928;1070.6573896353093;1090.465671820802;1091.1816137896578;1093.0272057868503;14761.483348082766;14859.777103343675;15027.624309392353;15110.622234444227;15504.147271900154;38328.80835595804;38995.6100438992;39397.01507462336;40027;40215;46406.30390544077;46763.53236467592;47423.76288118537;47724.761376192895;49649;8187.672493100209;8193.262606365217;8262.586870656323;8308.584570771558;8402.495850249004;12873;13030.218186908816;13127.803082953573;13225.867741322463;13749.862501374859;10868.78262434731;11050.33697978124;11063.889361106287;11392.601258955896;11757.76484470323;9914;10073.345232559861;10106.49467526606;10202.540885660312;10229.74425639365;11080.833787493033;11108.277961932448;11372.374519401512;11628.302301861915;11793.882061179278;7959.880601790862;8115.188481151838;8257.669693212445;8302.584870756558;8358.122397148212;11102.333859968428;11203.383813890314;11223.66329010131;11325.943370283096;11695.064394848221 01:52:43 INFO - PID 6449 | |3;mootools.html;1220.2556440571252;1273.0197747734244;1286.9742605147903;1289.922604643722;1300.830891984042;9895.406275623458;10358;10374.429406382646;10783.13734901208;11039.944800275987;10858.457077146131;10988.285761425503;11023.338599684024;11024.50389732462;11063.72340691483;2071.7203177570987;2074.751029876417;2088.248230636977;2089.89550522474;2107.8840663763485;2046.498607841081;2083.270855200683;2083.4166433398673;2090.9790902090936;2098.0873320105725;2022.2315520102413;2040.0513761101085;2045.9079341429672;2056.598963202176;2067.5658111796542;1985.1364656374524;1988.3537850198743;1991.950201244966;2000.6098810731912;2000.7098970649247;1918;1940.7865134835156;1962.5093726568357;2054.342610364684;2063.515073957619;10489;10588.735281617945;10594.947025264824;10595.629152979685;10633.893661063368;5381.596380271495;5594.0769772987815;5606.187102870102;5616.859578510529;5630.296212973379;3153.4323821712082;3266.281418088017;3322.7674062815613;3323.0861513084096;3327.1515763480156;978.0708327089294;994.5126887824986;1000.6597756762662;1009.2430676992255;1047.4919663962971;15505.767413488808;15556.5999060084;15561.922190388976;15656.295466704025;15701.136437495934;4487.820487180476;4617.215073437524;4691.929621055687;4701.412323459567;4704.270838020112;11471.024962878082;11492.48283827213;11526.769464610828;11530;11538;5243.632945693841;5381.48875856793;5385.892282154335;5397.541208997279;5418.349798024262;5048.823291184791;5086.872828179326;5124.256982737482;5155.587552995827;5174.97412512935;3892.2799282132896;3904.316744569689;3954.1696243789124;3970.285348637273;3973.523177218752;1063.1176123817213;1069.1713921710736;1076.9138468922467;1077.2782235901964;1078.8112080385902;691.3328637864485;715.5313269808283;718.4109030594863;729.6716477585107;730.3244498838659;5490.56075513958;5553.16702494629;5642.6614403135945;5713.914291285634;5725.59920805548;505.60310156527686;524.7848382163295;526.2422112158556;847.6948298612494;871.7515508080166;3829.6744776694295;4010.318245898204;4067.918641627151;4091.3044782387065;4100.856470023535;13023.47906083746;13807.723845523033;14110.082844615066;14115.082519636191;14146.151230926178;13333.399997000159;13720.382582783799;13897.166170029628;13920.164790112625;13984.300784960711;7718.768436946788;7803.453758236869;7938.6030698466;7939.126696063428;7965.322947549407;5940.435658612425;6037.547183961133;6234.750609975732;6257.838472717116;6275.623462592259;12481.126321157431;12485.375731213222;12539.498420063095;12641.431135598736;12653.620391388273;12743.299118548199;12776.233425994471;12803.359832008176;12886.548970785978;12919.289439080942;7893.486923349964;8031.357491400557;8070.677172913018;8071.07182674;8088.595570221348;9868.802623947337;9931.900680993387;9943.900560994298;9980.301378903407;10029.247806414402;8278.048024477263;8368.748937531884;8417.957910210655;8429.494230345954;8528.104549022264;8065.395095367753;8163.183681631789;8206.507609543447;8212.260896519341;8289.336853051862;7617.428692847948;7621.733239336597;7649.464537482323;7708.460407771627;7725.5364678119495;5149.459306772735;5187;5243.292155558951;5246.527812496741;5327.360716714019;5098.694078355311;5132.538071573576;5133.563647090039;5198.298229738937;5290.206469029677 01:52:43 INFO - PID 6449 | |4;prototype.html;339.75707369231;349.11847584848255;350.0880207060609;351.7361978516113;354.7587640404524;5669.376368599456;5688.459596338341;5725.828225153252;5733.340665823437;5751.971240143798;5348.224507446418;5568.470995255454;5591.1054231323;5593.356763972144;5594.076977298751;4152.252594532983;4177.060161463677;4180.331147016478;4180.414741936132;4200.894977625553;4132.690048246379;4160.45914031177;4172.16556688662;4194.538600753915;4203.47456567929;4062.7156099073077;4099.200655872106;4110.7328023678365;4123.9381409278885;4125.587441255881;3993.5207775067033;4076.694247931402;4114.403411505315;4127.422160897477;4152.792360381969;3581.5881173665102;3581.982090089533;3584.9283014339826;3586.3006713690843;3605.56733192016;5353.705546194958;5413.756380962866;5445.646033007842;5451.318585176853;5457.863553411157;4738.739369334685;4851.932574833566;4892.290617860391;4896.706197628154;4920.532549407801;5900.970495147497;5907.232059832238;5915.674637894914;5933.7329820157765;5943.592844897823;3997.300472417315;4000.819963101638;4006.8196931137877;4026.375911733686;4047.736897101679;6008.729607167688;6109.083637454373;6283.937160628381;6285.214348206474;6287.685615719196;4048.473698419187;4078.592140785957;4082.4284600156;4083.91832163361;4087.1621317629815;4130.050088479667;4134.421181034658;4138.275801734684;4141.751494910315;4144.357624568197;4849.660523763303;4951.653384263139;5067.189249720101;5084.669496482717;5099.158638824627;5972.850678733066;5975.7609695612555;5984.670843103671;5992.970035149883;6016.939830601726;1576.0937460959994;1584.5800862771375;1612.8306527814646;1613.0321806915756;1615.2327644368831;1117.1453837814079;1120.1934607082887;1143.1197977557235;1149.6665966869514;1150.183369807446;5042.672226305279;5078;5113.233015047773;5119.48805119485;5121.718305493234;6106.297775755756;6109.8472538186015;6112.51099912006;6122.969385153135;6170.56806023583;4966.428860681068;5012.498750125032;5051.898962020739;5056.696598204119;5087.516685914832;3214.51782232667;3241.594800649919;3257.7719559631073;3258.9837050814594;3263;5622.297212848394;5632.436756324335;5664.943350566524;5670.432956704297;5675.602707810496;6041.78853740117;6225.315215326311;6268.843278917972;6302.401271879256;6316.715747791361;5641.435856414326;5665.518430933417;5680.715964201773;5686.459786320293;5700.087985922232;6039.36937027009;6065.72704228302;6119.908201376894;6123.581185431819;6205.720742566505;5876.324222714443;6048.366940926014;6064.181335519825;6072.969635151972;6076.1493390924425;5604.85987850307;5614.550835933197;5634.915081419901;5653.943460565341;5675.7445914933105;4104.199681062164;4107.67138628903;4121.979390103031;4135.1316223593385;4145.502539695256;5992.520598352208;6066.332703402534;6143.6928153593035;6160.199174107337;6174.845628859135;5929.6145750526075;5959.940400596112;5960.612560183574;5965.940340596539;5979.521638269015;5765.365809761007;5930.52555795544;5944.0489521678055;5969.283685957713;5981.970090149522;5795.971020144872;5810.506106980785;5829.53363730892;5852.619579727304;5881.529477641693;5992.940070599238;5994.190784244071;6017.699115044318;6020.698965051817;6049.153118563317;5674.404187560247;5755.366909639853;5799.449052340021;5811.360750317546;5845.444682755132 01:52:43 INFO - PID 6449 | |5;yui.html;690.6995456976219;700.4046560423637;715.5992644119293;722.9638518074095;740.2042803985717;11697.766044679101;12316.753664926697;13277.734445311087;13872.237026963512;13973.650658733537;14696.853031469682;15124.546263612081;15210.543683689479;15304.387824487036;15321.540353789376;875.0549406640829;890.4256754393413;891.9687810926619;893.9642414303453;894.66897248018;898.1736802142045;899.6041741633693;899.7660608241839;900.1763386501343;900.6127365232948;918.7335672654922;930.6044930904338;931.2177770672633;932.4172392254841;932.5570354081791;921.5624672740597;929.5677509957902;934.700895713372;935.2845073518764;935.8642996765465;668.5353679192963;668.6389349751129;668.8160755792147;669.5748199893054;670.8289386206535;14924.403023879031;15002.62493437662;15107.773383399257;15143.697126057526;15158.166300853449;14337.71324573513;14880.404783808635;14880.776788348183;15063.698726025528;15070.246487675573;12377.133600647958;12501.312427816467;12677.302748348837;12677.683057923534;12787.488500459971;2831.9716802831913;2833.546632538784;2838.977967931543;2854.7716182705335;2865.8567071646335;72039;73904.63047684728;74925.25074749238;75923.24076759272;78379;11765.176437649454;12113.576024839089;12136.393180340947;12174.391280435942;12243;23895;24171.637425438632;24258.51482970331;24709.258722238363;24856.502869942862;6366.6180029198395;6388.744450222032;6396.360363963567;6415.935840641534;6425.036244563353;3009.5335223040465;3067.3098552825436;3083.367909578531;3084.799488033271;3099.628044634658;3600.261946301002;3655.3420384330543;3678.2459595782802;3698.7041036716996;3701.5188025556504;708.5783958544657;717.3579646216607;722.5772922840198;725.0357025156529;753.2806170107534;564.8277275431033;572.6020415810972;593.8841925824423;596.1594152245314;604.1119554255238;9835.591292723273;10304.536295866705;10415.270931034755;10661.147108231324;10704.411257380918;547.3295213363598;551.2613098448098;555.1783360626296;557.4704031170323;561.3432284227464;4809.735464549413;4817.590504807061;4868.41579010521;4928.778204980785;5024.397072351341;85807.57096214479;86678;87339;88638.55680721556;89022.10977890268;72176.27823721801;73128.63435682788;73584.63207683928;74344.62827685828;74616.25383746202;57310.71344643167;58457;61644.383556164765;62088;62508.37491625026;10370.740731481472;10434.947825260826;10784.89215107839;10871.728206794893;11054.778904422028;27114.864425677744;27507.862460687567;27957.161285161477;28151;29035.854820725763;35077.64922350744;35176;52123;53405.732971334895;53567.73216133894;13276.07067505304;13423;13472;13485.258310792608;13615.115017523829;15386.769198461809;15448.845511544741;16174.433894813625;16311.59221019484;16687.415940442028;15258.31337589789;15404;15474.303656335267;15499.457518987232;15725.135117568643;14198.858011419754;14703.632409189428;15011;15012.399504020153;15165.772513412528;14126.43494260218;14903.180325082225;15114.773278401053;15204.695906082037;15465.613359666098;11510.424478776195;11523.769524609294;11570.710732231424;11691.707707307385;12054.879451205376;11802.35087070185;12246.755064898474;12264.938675306566;12376.786931577988;12450.377481125726 01:52:43 INFO - PID 6449 | __end_tp_report 01:52:43 INFO - PID 6449 | __start_cc_report 01:52:43 INFO - PID 6449 | _x_x_mozilla_cycle_collect,331 01:52:43 INFO - PID 6449 | __end_cc_report 01:52:43 INFO - PID 6449 | __startTimestamp1495875163079__endTimestamp 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | ------- Summary: start ------- 01:52:43 INFO - PID 6449 | Number of tests: 6 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | [#0] dojo.html Cycles:165 Average:5302.87 Median:5466.84 stddev:759.63 (13.9%) stddev-sans-first:735.16 01:52:43 INFO - PID 6449 | Values: 2753.9 2754.8 2770.8 2772.6 2784.2 5242.7 5246.9 5312.3 5349.1 5368.7 5342.9 5357.0 5390.0 5390.6 5434.0 4826.5 4858.6 4862.4 4870.8 4879.1 25.4 4860.1 4887.7 4888.6 4946.7 4453.1 4805.8 4815.1 4834.0 4852.3 4803.8 4846.0 4857.1 4873.4 4875.8 4625.6 4812.7 4819.4 4820.3 4855.9 5346.8 5348.0 5363.6 5392.4 5398.5 5811.2 5820.2 5822.2 5837.0 5841.7 5888.4 5889.9 5889.9 5905.9 5909.2 5329.6 5386.3 5457.0 5485.5 5507.8 6043.9 6055.9 6093.1 6104.9 6112.0 5760.9 5761.9 5802.2 5803.7 5807.7 5768.9 5769.5 5844.8 5858.9 5886.2 5801.5 5814.2 5818.0 5838.7 5867.6 5791.1 5792.3 5796.7 5803.5 5817.2 4782.5 4824.1 4829.1 4834.6 4880.0 4135.9 4185.8 4196.4 4255.1 4264.0 4923.2 4961.2 4967.2 4974.8 5003.0 5791.7 5880.0 5913.2 5954.7 5958.1 4868.3 4872.0 4942.5 4970.8 4971.0 5154.3 5167.8 5172.4 5178.2 5189.1 5421.5 5424.0 5433.2 5439.1 5466.8 5691.7 5759.4 5839.5 5896.3 5896.6 5359.9 5366.7 5392.2 5398.8 5401.8 5697.6 5699.6 5719.7 5723.9 5774.1 5683.2 5769.2 5796.2 5805.1 5916.9 5684.5 5685.6 5691.6 5714.3 5750.5 5620.0 5654.3 5656.1 5674.9 5678.7 5530.9 5651.8 5656.0 5677.7 5704.1 5725.3 5757.5 5764.8 5769.4 5775.2 5592.6 5595.2 5603.7 5604.3 5607.8 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | [#1] ext.html Cycles:180 Average:28986.59 Median:16655.42 stddev:38548.32 (231.4%) stddev-sans-first:38597.84 01:52:43 INFO - PID 6449 | Values: 675.7 702.2 727.7 1077.9 1089.9 31423.7 31867.5 32055.0 32073.4 32118.2 33587.5 33861.2 34098.5 34116.5 34163.7 6528.7 6599.5 6762.2 6862.3 6881.8 6578.4 6693.4 6699.3 6702.4 6706.0 6669.7 6743.2 6766.3 6789.4 6800.7 6694.4 6708.8 6718.8 6722.2 6726.7 6489.5 6503.1 6535.3 6549.2 6566.9 30151.2 30231.4 30278.4 30345.0 30415.1 2993.4 3022.1 3023.3 3026.6 3033.0 22956.2 22974.4 22974.7 22992.3 22998.1 2958.2 2958.9 3032.4 3035.5 3042.1 128909.4 129358.4 129406.0 145012.0 150330.2 38445.0 38600.0 38605.6 38691.4 38723.1 40063.4 40198.0 40230.0 40236.6 40381.6 11305.7 11408.3 11413.6 11422.5 11469.9 9124.0 9134.0 9144.3 9147.3 9191.0 12488.3 12492.8 12521.2 12595.9 12741.6 5161.8 5208.9 5264.8 5331.4 5378.6 855.9 884.6 892.4 895.3 920.2 20099.5 20813.7 20994.2 21007.6 21214.9 957.2 960.3 961.0 963.8 974.0 11044.5 11084.0 11084.7 11116.7 11117.8 170704.0 171068.0 171081.0 171181.0 171630.1 39429.4 39683.2 39704.4 39708.2 39770.0 1196.5 1224.1 1230.7 1232.8 1235.9 19636.6 19828.7 20147.5 20369.8 20415.7 28686.7 28735.7 28852.3 28872.3 29010.9 136707.0 137252.3 137363.0 138512.3 139231.0 28461.1 28508.1 28718.9 29647.6 29671.0 34858.0 34932.1 35088.3 35240.8 35272.8 25986.5 27046.9 27077.9 27636.9 27702.7 26486.7 26679.9 27240.6 27334.5 27946.7 30503.1 30652.0 30802.5 30816.8 30894.7 25946.2 26432.9 26753.9 27065.7 27484.3 21241.8 22114.4 22130.4 22162.3 22287.8 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | [#2] jquery.html Cycles:180 Average:24388.74 Median:38662.21 stddev:57611.31 (149.0%) stddev-sans-first:57744.78 01:52:43 INFO - PID 6449 | Values: 403.6 403.8 413.1 425.8 433.4 16817.2 17415.3 17437.1 18615.8 19116.6 19002.4 19410.0 19529.0 19588.2 19616.2 5306.2 5359.3 5415.8 5451.9 5548.0 5383.8 5455.4 5461.3 5471.0 5477.8 5244.4 5339.9 5427.6 5446.6 5461.4 5353.7 5381.9 5393.5 5396.8 5434.3 4786.8 4829.1 4861.8 4864.0 4891.3 22040.6 22286.3 22350.0 22471.6 22474.2 1611.7 1646.7 1653.3 1658.5 1659.4 14216.5 14328.3 14787.7 14897.6 15014.6 1953.2 2013.6 2026.8 2029.5 2042.5 92276.5 95690.0 95911.0 99188.0 100827.0 10620.6 10879.6 11003.6 11207.7 11279.0 15275.9 15401.2 15439.9 15448.0 15469.2 7229.1 7405.8 7418.2 7577.4 7579.9 6687.9 6805.9 6919.4 6921.9 6927.1 6953.6 7034.9 7039.9 7101.2 7163.5 1996.7 1997.8 2048.3 2074.2 2154.3 832.4 868.3 869.6 872.6 895.8 17060.3 17326.0 17415.7 17441.1 18172.0 886.6 891.7 892.8 896.4 915.1 9470.7 9721.3 9741.3 9785.2 9922.5 337947.0 342190.9 343759.0 344706.0 344892.0 76583.6 78012.6 78387.2 78562.6 78736.0 1041.8 1070.7 1090.5 1091.2 1093.0 14761.5 14859.8 15027.6 15110.6 15504.1 38328.8 38995.6 39397.0 40027.0 40215.0 46406.3 46763.5 47423.8 47724.8 49649.0 8187.7 8193.3 8262.6 8308.6 8402.5 12873.0 13030.2 13127.8 13225.9 13749.9 10868.8 11050.3 11063.9 11392.6 11757.8 9914.0 10073.3 10106.5 10202.5 10229.7 11080.8 11108.3 11372.4 11628.3 11793.9 7959.9 8115.2 8257.7 8302.6 8358.1 11102.3 11203.4 11223.7 11325.9 11695.1 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | [#3] mootools.html Cycles:180 Average:6425.06 Median:3929.24 stddev:4285.10 (109.1%) stddev-sans-first:4279.27 01:52:43 INFO - PID 6449 | Values: 1220.3 1273.0 1287.0 1289.9 1300.8 9895.4 10358.0 10374.4 10783.1 11039.9 10858.5 10988.3 11023.3 11024.5 11063.7 2071.7 2074.8 2088.2 2089.9 2107.9 2046.5 2083.3 2083.4 2091.0 2098.1 2022.2 2040.1 2045.9 2056.6 2067.6 1985.1 1988.4 1992.0 2000.6 2000.7 1918.0 1940.8 1962.5 2054.3 2063.5 10489.0 10588.7 10594.9 10595.6 10633.9 5381.6 5594.1 5606.2 5616.9 5630.3 3153.4 3266.3 3322.8 3323.1 3327.2 978.1 994.5 1000.7 1009.2 1047.5 15505.8 15556.6 15561.9 15656.3 15701.1 4487.8 4617.2 4691.9 4701.4 4704.3 11471.0 11492.5 11526.8 11530.0 11538.0 5243.6 5381.5 5385.9 5397.5 5418.3 5048.8 5086.9 5124.3 5155.6 5175.0 3892.3 3904.3 3954.2 3970.3 3973.5 1063.1 1069.2 1076.9 1077.3 1078.8 691.3 715.5 718.4 729.7 730.3 5490.6 5553.2 5642.7 5713.9 5725.6 505.6 524.8 526.2 847.7 871.8 3829.7 4010.3 4067.9 4091.3 4100.9 13023.5 13807.7 14110.1 14115.1 14146.2 13333.4 13720.4 13897.2 13920.2 13984.3 7718.8 7803.5 7938.6 7939.1 7965.3 5940.4 6037.5 6234.8 6257.8 6275.6 12481.1 12485.4 12539.5 12641.4 12653.6 12743.3 12776.2 12803.4 12886.5 12919.3 7893.5 8031.4 8070.7 8071.1 8088.6 9868.8 9931.9 9943.9 9980.3 10029.2 8278.0 8368.7 8418.0 8429.5 8528.1 8065.4 8163.2 8206.5 8212.3 8289.3 7617.4 7621.7 7649.5 7708.5 7725.5 5149.5 5187.0 5243.3 5246.5 5327.4 5098.7 5132.5 5133.6 5198.3 5290.2 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | [#4] prototype.html Cycles:180 Average:4852.03 Median:5513.17 stddev:1444.89 (26.2%) stddev-sans-first:1408.69 01:52:43 INFO - PID 6449 | Values: 339.8 349.1 350.1 351.7 354.8 5669.4 5688.5 5725.8 5733.3 5752.0 5348.2 5568.5 5591.1 5593.4 5594.1 4152.3 4177.1 4180.3 4180.4 4200.9 4132.7 4160.5 4172.2 4194.5 4203.5 4062.7 4099.2 4110.7 4123.9 4125.6 3993.5 4076.7 4114.4 4127.4 4152.8 3581.6 3582.0 3584.9 3586.3 3605.6 5353.7 5413.8 5445.6 5451.3 5457.9 4738.7 4851.9 4892.3 4896.7 4920.5 5901.0 5907.2 5915.7 5933.7 5943.6 3997.3 4000.8 4006.8 4026.4 4047.7 6008.7 6109.1 6283.9 6285.2 6287.7 4048.5 4078.6 4082.4 4083.9 4087.2 4130.1 4134.4 4138.3 4141.8 4144.4 4849.7 4951.7 5067.2 5084.7 5099.2 5972.9 5975.8 5984.7 5993.0 6016.9 1576.1 1584.6 1612.8 1613.0 1615.2 1117.1 1120.2 1143.1 1149.7 1150.2 5042.7 5078.0 5113.2 5119.5 5121.7 6106.3 6109.8 6112.5 6123.0 6170.6 4966.4 5012.5 5051.9 5056.7 5087.5 3214.5 3241.6 3257.8 3259.0 3263.0 5622.3 5632.4 5664.9 5670.4 5675.6 6041.8 6225.3 6268.8 6302.4 6316.7 5641.4 5665.5 5680.7 5686.5 5700.1 6039.4 6065.7 6119.9 6123.6 6205.7 5876.3 6048.4 6064.2 6073.0 6076.1 5604.9 5614.6 5634.9 5653.9 5675.7 4104.2 4107.7 4122.0 4135.1 4145.5 5992.5 6066.3 6143.7 6160.2 6174.8 5929.6 5959.9 5960.6 5965.9 5979.5 5765.4 5930.5 5944.0 5969.3 5982.0 5796.0 5810.5 5829.5 5852.6 5881.5 5992.9 5994.2 6017.7 6020.7 6049.2 5674.4 5755.4 5799.4 5811.4 5845.4 01:52:43 INFO - PID 6449 | 01:52:43 INFO - PID 6449 | [#5] yui.html Cycles:180 Average:17359.22 Median:3075.34 stddev:22391.45 (728.1%) stddev-sans-first:22419.28 01:52:43 INFO - PID 6449 | Values: 690.7 700.4 715.6 723.0 740.2 11697.8 12316.8 13277.7 13872.2 13973.7 14696.9 15124.5 15210.5 15304.4 15321.5 875.1 890.4 892.0 894.0 894.7 898.2 899.6 899.8 900.2 900.6 918.7 930.6 931.2 932.4 932.6 921.6 929.6 934.7 935.3 935.9 668.5 668.6 668.8 669.6 670.8 14924.4 15002.6 15107.8 15143.7 15158.2 14337.7 14880.4 14880.8 15063.7 15070.2 12377.1 12501.3 12677.3 12677.7 12787.5 2832.0 2833.5 2839.0 2854.8 2865.9 72039.0 73904.6 74925.3 75923.2 78379.0 11765.2 12113.6 12136.4 12174.4 12243.0 23895.0 24171.6 24258.5 24709.3 24856.5 6366.6 6388.7 6396.4 6415.9 6425.0 3009.5 3067.3 3083.4 3084.8 3099.6 3600.3 3655.3 3678.2 3698.7 3701.5 708.6 717.4 722.6 725.0 753.3 564.8 572.6 593.9 596.2 604.1 9835.6 10304.5 10415.3 10661.1 10704.4 547.3 551.3 555.2 557.5 561.3 4809.7 4817.6 4868.4 4928.8 5024.4 85807.6 86678.0 87339.0 88638.6 89022.1 72176.3 73128.6 73584.6 74344.6 74616.3 57310.7 58457.0 61644.4 62088.0 62508.4 10370.7 10434.9 10784.9 10871.7 11054.8 27114.9 27507.9 27957.2 28151.0 29035.9 35077.6 35176.0 52123.0 53405.7 53567.7 13276.1 13423.0 13472.0 13485.3 13615.1 15386.8 15448.8 16174.4 16311.6 16687.4 15258.3 15404.0 15474.3 15499.5 15725.1 14198.9 14703.6 15011.0 15012.4 15165.8 14126.4 14903.2 15114.8 15204.7 15465.6 11510.4 11523.8 11570.7 11691.7 12054.9 11802.4 12246.8 12264.9 12376.8 12450.4 01:52:43 INFO - PID 6449 | -------- Summary: end -------- 01:52:43 INFO - PID 6449 | 01:52:43 INFO - TEST-INFO | 6449: exit 0 01:52:43 INFO - TEST-OK | dromaeo_css | took 1146467ms 01:52:43 INFO - TEST-START | kraken 01:52:43 INFO - operating with platform_type : linux_ 01:52:43 INFO - Initialising browser for kraken test... 01:52:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60784/getInfo.html -profile /tmp/tmpoy6NkF/profile 01:52:43 INFO - TEST-INFO | started process 6870 (/builds/slave/test/build/application/firefox/firefox http://localhost:60784/getInfo.html) 01:53:18 INFO - TEST-INFO | 6870: killed by SIGTERM 01:53:18 INFO - Browser initialized. 01:53:18 INFO - Running cycle 1/1 for kraken test... 01:53:18 INFO - TEST-INFO | started process 7091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoy6NkF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:53:30 INFO - PID 7091 | RSS: Main: 172478464 01:53:30 INFO - PID 7091 | 01:53:33 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60784/tests/kraken/driver.html?testName=audio-beat-detection) 01:53:34 INFO - PID 7091 | RSS: Main: 178167808 01:53:34 INFO - PID 7091 | 01:53:37 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60784/tests/kraken/driver.html?testName=audio-dft) 01:53:37 INFO - PID 7091 | RSS: Main: 177709056 01:53:37 INFO - PID 7091 | 01:53:40 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60784/tests/kraken/driver.html?testName=audio-fft) 01:53:41 INFO - PID 7091 | RSS: Main: 175284224 01:53:41 INFO - PID 7091 | 01:53:43 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60784/tests/kraken/driver.html?testName=audio-oscillator) 01:53:43 INFO - PID 7091 | RSS: Main: 175620096 01:53:43 INFO - PID 7091 | 01:53:46 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60784/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:53:46 INFO - PID 7091 | RSS: Main: 175931392 01:53:46 INFO - PID 7091 | 01:53:49 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60784/tests/kraken/driver.html?testName=imaging-darkroom) 01:53:50 INFO - PID 7091 | RSS: Main: 179773440 01:53:50 INFO - PID 7091 | 01:53:54 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60784/tests/kraken/driver.html?testName=imaging-desaturate) 01:53:54 INFO - PID 7091 | RSS: Main: 175685632 01:53:54 INFO - PID 7091 | 01:53:57 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60784/tests/kraken/driver.html?testName=json-parse-financial) 01:53:58 INFO - PID 7091 | RSS: Main: 176390144 01:53:58 INFO - PID 7091 | 01:53:59 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60784/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:54:00 INFO - PID 7091 | RSS: Main: 176566272 01:54:00 INFO - PID 7091 | 01:54:02 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:54:03 INFO - PID 7091 | RSS: Main: 176574464 01:54:03 INFO - PID 7091 | 01:54:05 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:54:06 INFO - PID 7091 | RSS: Main: 176783360 01:54:06 INFO - PID 7091 | 01:54:08 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:54:09 INFO - PID 7091 | RSS: Main: 177004544 01:54:09 INFO - PID 7091 | 01:54:12 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:54:13 INFO - PID 7091 | RSS: Main: 176619520 01:54:13 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | Cycle 1(1): loaded http://localhost:60784/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60784/tests/kraken/driver.html?testName=ai-astar) 01:54:15 INFO - PID 7091 | RSS: Main: 178012160 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | __start_tp_report 01:54:15 INFO - PID 7091 | _x_x_mozilla_page_load 01:54:15 INFO - PID 7091 | _x_x_mozilla_page_load_details 01:54:15 INFO - PID 7091 | |i|pagename|runs| 01:54:15 INFO - PID 7091 | |0;ai-astar;102;110;110;111;110;111;111;110;110;112 01:54:15 INFO - PID 7091 | |1;audio-beat-detection;157;123;121;171;129;124;129;124;153;125 01:54:15 INFO - PID 7091 | |2;audio-dft;164;164;163;164;165;168;165;168;162;168 01:54:15 INFO - PID 7091 | |3;audio-fft;76;73;73;116;76;75;74;74;102;75 01:54:15 INFO - PID 7091 | |4;audio-oscillator;83;83;107;84;84;83;97;83;84;108 01:54:15 INFO - PID 7091 | |5;imaging-gaussian-blur;117;115;114;114;114;115;113;115;115;115 01:54:15 INFO - PID 7091 | |6;imaging-darkroom;151;152;151;151;150;150;149;150;151;149 01:54:15 INFO - PID 7091 | |7;imaging-desaturate;94;96;94;94;95;94;95;95;94;94 01:54:15 INFO - PID 7091 | |8;json-parse-financial;58;54;59;54;55;56;54;54;55;56 01:54:15 INFO - PID 7091 | |9;json-stringify-tinderbox;56;58;58;57;57;57;57;57;56;56 01:54:15 INFO - PID 7091 | |10;stanford-crypto-aes;88;88;88;95;95;87;87;88;123;88 01:54:15 INFO - PID 7091 | |11;stanford-crypto-ccm;142;147;133;138;133;133;132;137;153;140 01:54:15 INFO - PID 7091 | |12;stanford-crypto-pbkdf2;190;199;199;196;198;191;194;190;189;193 01:54:15 INFO - PID 7091 | |13;stanford-crypto-sha256-iterative;73;72;76;73;76;72;74;72;70;71 01:54:15 INFO - PID 7091 | __end_tp_report 01:54:15 INFO - PID 7091 | __start_cc_report 01:54:15 INFO - PID 7091 | _x_x_mozilla_cycle_collect,925 01:54:15 INFO - PID 7091 | __end_cc_report 01:54:15 INFO - PID 7091 | __startTimestamp1495875255346__endTimestamp 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | ------- Summary: start ------- 01:54:15 INFO - PID 7091 | Number of tests: 14 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.50 stddev:2.79 (2.5%) stddev-sans-first:0.73 01:54:15 INFO - PID 7091 | Values: 102.0 110.0 110.0 111.0 110.0 111.0 111.0 110.0 110.0 112.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:129.00 stddev:17.81 (13.8%) stddev-sans-first:17.12 01:54:15 INFO - PID 7091 | Values: 157.0 123.0 121.0 171.0 129.0 124.0 129.0 124.0 153.0 125.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#2] audio-dft Cycles:10 Average:165.10 Median:165.00 stddev:2.18 (1.3%) stddev-sans-first:2.28 01:54:15 INFO - PID 7091 | Values: 164.0 164.0 163.0 164.0 165.0 168.0 165.0 168.0 162.0 168.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#3] audio-fft Cycles:10 Average:81.40 Median:74.50 stddev:14.95 (20.1%) stddev-sans-first:15.73 01:54:15 INFO - PID 7091 | Values: 76.0 73.0 73.0 116.0 76.0 75.0 74.0 74.0 102.0 75.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:83.50 stddev:10.35 (12.4%) stddev-sans-first:10.70 01:54:15 INFO - PID 7091 | Values: 83.0 83.0 107.0 84.0 84.0 83.0 97.0 83.0 84.0 108.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.00 stddev:1.06 (0.9%) stddev-sans-first:0.73 01:54:15 INFO - PID 7091 | Values: 117.0 115.0 114.0 114.0 114.0 115.0 113.0 115.0 115.0 115.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:151.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 01:54:15 INFO - PID 7091 | Values: 151.0 152.0 151.0 151.0 150.0 150.0 149.0 150.0 151.0 149.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 01:54:15 INFO - PID 7091 | Values: 94.0 96.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 94.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:1.78 (3.2%) stddev-sans-first:1.64 01:54:15 INFO - PID 7091 | Values: 58.0 54.0 59.0 54.0 55.0 56.0 54.0 54.0 55.0 56.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#9] json-stringify-tinderbox Cycles:10 Average:56.90 Median:57.00 stddev:0.74 (1.3%) stddev-sans-first:0.71 01:54:15 INFO - PID 7091 | Values: 56.0 58.0 58.0 57.0 57.0 57.0 57.0 57.0 56.0 56.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:88.00 stddev:11.08 (12.6%) stddev-sans-first:11.62 01:54:15 INFO - PID 7091 | Values: 88.0 88.0 88.0 95.0 95.0 87.0 87.0 88.0 123.0 88.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:139.00 stddev:6.92 (5.0%) stddev-sans-first:7.25 01:54:15 INFO - PID 7091 | Values: 142.0 147.0 133.0 138.0 133.0 133.0 132.0 137.0 153.0 140.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.90 Median:195.00 stddev:3.90 (2.0%) stddev-sans-first:3.87 01:54:15 INFO - PID 7091 | Values: 190.0 199.0 199.0 196.0 198.0 191.0 194.0 190.0 189.0 193.0 01:54:15 INFO - PID 7091 | 01:54:15 INFO - PID 7091 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:73.00 stddev:1.97 (2.7%) stddev-sans-first:2.09 01:54:15 INFO - PID 7091 | Values: 73.0 72.0 76.0 73.0 76.0 72.0 74.0 72.0 70.0 71.0 01:54:15 INFO - PID 7091 | -------- Summary: end -------- 01:54:15 INFO - PID 7091 | 01:54:16 INFO - TEST-INFO | 7091: exit 0 01:54:16 INFO - TEST-OK | kraken | took 92471ms 01:54:16 INFO - SUITE-END | took 1238s 01:54:16 INFO - Completed test suite (00:20:39) 01:54:16 INFO - javascript benchmark 01:54:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6775.171411825007, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2753.8760755765993, 2754.834709917404, 2770.8198967067146, 2772.639556857608, 2784.2204182828814, 5242.737863106844, 5246.895062098761, 5312.256284120226, 5349.144136938091, 5368.70472124033, 5342.946570534294, 5357.008953343634, 5389.973050134754, 5390.649607775492, 5434.0218760623075, 4826.517348265163, 4858.611311095113, 4862.367892174012, 4870.780814863339, 4879.121758083544, 25.375782390749198, 4860.125177468054, 4887.682300650464, 4888.633352498559, 4946.72792996385, 4453.109378124355, 4805.783739731703, 4815.085133824562, 4833.975830120827, 4852.344933433977, 4803.807847686088, 4846.0, 4857.125717370872, 4873.439554451248, 4875.756212189376, 4625.629949604024, 4812.711237325771, 4819.397575303087, 4820.276958456225, 4855.878603034917, 5346.8395948121615, 5348.037353276409, 5363.570914326844, 5392.379876314235, 5398.4871437213405, 5811.244538210048, 5820.156077368758, 5822.184894114827, 5837.0, 5841.678707671076, 5888.381719919433, 5889.852753681193, 5889.882202355929, 5905.940940590582, 5909.202257695242, 5329.600279979017, 5386.272853164851, 5457.0, 5485.50630443262, 5507.752151153208, 6043.939560604425, 6055.939440605537, 6093.086037094383, 6104.877902441926, 6111.96944015277, 5760.934227167903, 5761.884762304814, 5802.158686990363, 5803.6807975560905, 5807.68057756819, 5768.913466298009, 5769.509591684669, 5844.824655260349, 5858.882822343529, 5886.175935369051, 5801.53587712982, 5814.215080964016, 5817.970910145506, 5838.7080645967535, 5867.589268751147, 5791.0734282514595, 5792.333881603585, 5796.710164491758, 5803.506701930299, 5817.214676018683, 4782.545658162469, 4824.107540105092, 4829.058333624979, 4834.564889159992, 4880.0, 4135.91728165435, 4185.811638476276, 4196.433481480022, 4255.106427650167, 4264.040590867092, 4923.212286034217, 4961.181405068195, 4967.155583550805, 4974.751262436864, 5002.974985125051, 5791.652500849962, 5880.0, 5913.231279933581, 5954.732037058343, 5958.1062840573395, 4868.3427737255015, 4871.975640121847, 4942.456329803718, 4970.801167953314, 4970.975145124251, 5154.30416893722, 5167.819126330561, 5172.431032586413, 5178.249153872667, 5189.117849965515, 5421.5391691706645, 5424.0, 5433.185022246695, 5439.102548079522, 5466.835994920159, 5691.7438715257085, 5759.366469688247, 5839.5036421904615, 5896.292444906639, 5896.616719913192, 5359.866003349947, 5366.651167674245, 5392.245085687929, 5398.811041613525, 5401.810936617199, 5697.572682048946, 5699.5725320600295, 5719.742611582572, 5723.8569035774435, 5774.133879918046, 5683.175939488834, 5769.1634712967225, 5796.246487956539, 5805.129230615442, 5916.852078698067, 5684.516816070681, 5685.630434021776, 5691.573132015198, 5714.314282286152, 5750.539956803529, 5620.0, 5654.293213348332, 5656.095024796013, 5674.858128546654, 5678.68767217807, 5530.861728456821, 5651.8304450866535, 5655.971720141373, 5677.687727175045, 5704.144378343116, 5725.341585717696, 5757.510611598061, 5764.769409223584, 5769.365369809401, 5775.249217601685, 5592.608517403905, 5595.188697638901, 5603.691796951207, 5604.299462567179, 5607.80372686954], "value": 5249.540778749445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.7263308360116, 702.181958018908, 727.7343769524125, 1077.8975997280256, 1089.8637670291214, 31423.685763142363, 31867.52198717021, 32055.0, 32073.35853282933, 32118.197045073885, 33587.496187557146, 33861.153471163234, 34098.488522672116, 34116.48825267623, 34163.65836341636, 6528.738850445977, 6599.505037122211, 6762.154730658668, 6862.279460656634, 6881.759138430156, 6578.440832529241, 6693.364130407604, 6699.330066993286, 6702.36327548882, 6706.0276259942275, 6669.666516674171, 6743.15710536183, 6766.25571187169, 6789.422899053587, 6800.65996700163, 6694.397504224618, 6708.765193218214, 6718.798436046926, 6722.226943901465, 6726.663666816639, 6489.513286503484, 6503.089567460559, 6535.313792051861, 6549.246836613803, 6566.868662626768, 30151.246218844484, 30231.395372092436, 30278.394432111454, 30345.0, 30415.087547373616, 2993.4462124506817, 3022.0631604202767, 3023.3499797543413, 3026.6065411496584, 3032.969670303291, 22956.19653312126, 22974.425639358982, 22974.65538016931, 22992.310230693107, 22998.08007679691, 2958.1569252763074, 2958.866850991689, 3032.438998785232, 3035.468792961223, 3042.1481985044, 128909.35545322308, 129358.3532082343, 129406.0, 145012.0, 150330.24834875864, 38445.0, 38600.0, 38605.613943860204, 38691.41962870559, 38723.127687231616, 40063.39904901429, 40198.0, 40230.0, 40236.59763402329, 40381.59618403837, 11305.660830175108, 11408.25846320003, 11413.60052398162, 11422.543098276143, 11469.88530114705, 9123.996360400351, 9133.95433022844, 9144.268458523302, 9147.313951453667, 9191.034941331198, 12488.313142777235, 12492.81260781089, 12521.186122901981, 12595.87404125947, 12741.55404560836, 5161.81933632321, 5208.921866172011, 5264.842054738364, 5331.360236771612, 5378.596605254622, 855.8502262104107, 884.5754038061766, 892.4466830565091, 895.2882458445551, 920.2132176988579, 20099.465794929027, 20813.687794683094, 20994.160233590792, 21007.634503757206, 21214.893925530272, 957.1768279279814, 960.2510042167121, 961.0437614573544, 963.8361478548663, 973.9951300243454, 11044.502997365138, 11084.0, 11084.722881927855, 11116.722081948015, 11117.777644447066, 170704.0, 171068.0, 171081.0, 171181.0, 171630.14184928997, 39429.40855887164, 39683.20633587312, 39704.404433933516, 39708.20583588312, 39770.0, 1196.5315357457669, 1224.0880543994604, 1230.655416483368, 1232.8027515597641, 1235.8506588872435, 19636.607267854848, 19828.70256946118, 20147.496312592302, 20369.79630203679, 20415.69376459384, 28686.713132868405, 28735.71264287414, 28852.278693032004, 28872.278193045342, 29010.854945725136, 136707.0, 137252.31373843466, 137363.0, 138512.3074384662, 139231.0, 28461.146165615064, 28508.144755657362, 28718.856405717837, 29647.555286671148, 29671.0, 34858.0, 34932.12669683278, 35088.298234034664, 35240.82379588085, 35272.82363588166, 25986.48027039411, 27046.864765676044, 27077.86461067682, 27636.861815690794, 27702.722972770014, 26486.7351326492, 26679.86660066687, 27240.591391129547, 27334.453310933273, 27946.72053279441, 30503.084907452812, 30652.0, 30802.537961930142, 30816.84591577028, 30894.69105308918, 25946.22161335163, 26432.867835660698, 26753.866230668722, 27065.72934270711, 27484.312892177055, 21241.78758212398, 22114.44713882166, 22130.446738831015, 22162.33512994613, 22287.77712222857], "value": 14041.880788573337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.58966731122024, 403.81626360006203, 413.1324219139806, 425.82753984636224, 433.3932494507689, 16817.15914204291, 17415.303387864486, 17437.128143592832, 18615.81384186161, 19116.61766764664, 19002.42992710221, 19409.991451325033, 19529.0, 19588.216471341166, 19616.215351385927, 5306.151015837467, 5359.330083739543, 5415.783368665248, 5451.945480545203, 5548.029094908375, 5383.81156659517, 5455.427180146087, 5461.317335333083, 5471.0, 5477.780888764445, 5244.423113457536, 5339.919901201485, 5427.62006659534, 5446.645968012086, 5461.399246082948, 5353.652012619167, 5381.919271210915, 5393.514583687487, 5396.784128634869, 5434.3207099112615, 4786.7958722122985, 4829.082474329866, 4861.756912154378, 4863.975680121612, 4891.3396691446915, 22040.559188816133, 22286.331410057563, 22350.0, 22471.550568988692, 22474.213402530833, 1611.6534944986884, 1646.7118254305449, 1653.3221379234576, 1658.461000174948, 1659.444086231114, 14216.502422415166, 14328.28358582077, 14787.704245915129, 14897.627559311104, 15014.62463438412, 1953.238237087537, 2013.5570174561574, 2026.827719643832, 2029.4926268432891, 2042.5302180498568, 92276.53861730716, 95690.0, 95911.0, 99188.0, 100826.99999999927, 10620.628278010232, 10879.619213327496, 11003.61487347955, 11207.66377008691, 11279.0, 15275.923620381827, 15401.22993850303, 15439.922800386152, 15448.0, 15469.226538673021, 7229.139747053542, 7405.7778266652085, 7418.18399976013, 7577.355924746348, 7579.924200758032, 6687.866242675216, 6805.93194068053, 6919.411849992706, 6921.861562768717, 6927.134108236471, 6953.61755103464, 7034.92965070353, 7039.894401583981, 7101.183363913216, 7163.498555101197, 1996.6805311150147, 1997.8401727862026, 2048.313814872005, 2074.222166687492, 2154.342925407734, 832.3590835056972, 868.3270465389247, 869.6477926439802, 872.5855218771032, 895.7536677413764, 17060.317587296617, 17326.0, 17415.73876391855, 17441.12794360277, 18172.0, 886.6276164011131, 891.7057371067532, 892.7857314244535, 896.3501461440534, 915.1031988651159, 9470.668526601536, 9721.319507634398, 9741.318107732392, 9785.168260697921, 9922.454265015349, 337947.0, 342190.89045547624, 343759.0, 344706.0, 344892.0, 76583.61708191535, 78012.60993695109, 78387.21612783913, 78562.6071869637, 78736.0, 1041.7656027393928, 1070.6573896353093, 1090.465671820802, 1091.1816137896578, 1093.0272057868503, 14761.483348082766, 14859.777103343675, 15027.624309392353, 15110.622234444227, 15504.147271900154, 38328.80835595804, 38995.6100438992, 39397.01507462336, 40027.0, 40215.0, 46406.30390544077, 46763.53236467592, 47423.76288118537, 47724.761376192895, 49649.0, 8187.672493100209, 8193.262606365217, 8262.586870656323, 8308.584570771558, 8402.495850249004, 12873.0, 13030.218186908816, 13127.803082953573, 13225.867741322463, 13749.862501374859, 10868.78262434731, 11050.33697978124, 11063.889361106287, 11392.601258955896, 11757.76484470323, 9914.0, 10073.345232559861, 10106.49467526606, 10202.540885660312, 10229.74425639365, 11080.833787493033, 11108.277961932448, 11372.374519401512, 11628.302301861915, 11793.882061179278, 7959.880601790862, 8115.188481151838, 8257.669693212445, 8302.584870756558, 8358.122397148212, 11102.333859968428, 11203.383813890314, 11223.66329010131, 11325.943370283096, 11695.064394848221], "value": 8826.407428188331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1220.2556440571252, 1273.0197747734244, 1286.9742605147903, 1289.922604643722, 1300.830891984042, 9895.406275623458, 10358.0, 10374.429406382646, 10783.13734901208, 11039.944800275987, 10858.457077146131, 10988.285761425503, 11023.338599684024, 11024.50389732462, 11063.72340691483, 2071.7203177570987, 2074.751029876417, 2088.248230636977, 2089.89550522474, 2107.8840663763485, 2046.498607841081, 2083.270855200683, 2083.4166433398673, 2090.9790902090936, 2098.0873320105725, 2022.2315520102413, 2040.0513761101085, 2045.9079341429672, 2056.598963202176, 2067.5658111796542, 1985.1364656374524, 1988.3537850198743, 1991.950201244966, 2000.6098810731912, 2000.7098970649247, 1918.0, 1940.7865134835156, 1962.5093726568357, 2054.342610364684, 2063.515073957619, 10489.0, 10588.735281617945, 10594.947025264824, 10595.629152979685, 10633.893661063368, 5381.596380271495, 5594.0769772987815, 5606.187102870102, 5616.859578510529, 5630.296212973379, 3153.4323821712082, 3266.281418088017, 3322.7674062815613, 3323.0861513084096, 3327.1515763480156, 978.0708327089294, 994.5126887824986, 1000.6597756762662, 1009.2430676992255, 1047.4919663962971, 15505.767413488808, 15556.5999060084, 15561.922190388976, 15656.295466704025, 15701.136437495934, 4487.820487180476, 4617.215073437524, 4691.929621055687, 4701.412323459567, 4704.270838020112, 11471.024962878082, 11492.48283827213, 11526.769464610828, 11530.0, 11538.0, 5243.632945693841, 5381.48875856793, 5385.892282154335, 5397.541208997279, 5418.349798024262, 5048.823291184791, 5086.872828179326, 5124.256982737482, 5155.587552995827, 5174.97412512935, 3892.2799282132896, 3904.316744569689, 3954.1696243789124, 3970.285348637273, 3973.523177218752, 1063.1176123817213, 1069.1713921710736, 1076.9138468922467, 1077.2782235901964, 1078.8112080385902, 691.3328637864485, 715.5313269808283, 718.4109030594863, 729.6716477585107, 730.3244498838659, 5490.56075513958, 5553.16702494629, 5642.6614403135945, 5713.914291285634, 5725.59920805548, 505.60310156527686, 524.7848382163295, 526.2422112158556, 847.6948298612494, 871.7515508080166, 3829.6744776694295, 4010.318245898204, 4067.918641627151, 4091.3044782387065, 4100.856470023535, 13023.47906083746, 13807.723845523033, 14110.082844615066, 14115.082519636191, 14146.151230926178, 13333.399997000159, 13720.382582783799, 13897.166170029628, 13920.164790112625, 13984.300784960711, 7718.768436946788, 7803.453758236869, 7938.6030698466, 7939.126696063428, 7965.322947549407, 5940.435658612425, 6037.547183961133, 6234.750609975732, 6257.838472717116, 6275.623462592259, 12481.126321157431, 12485.375731213222, 12539.498420063095, 12641.431135598736, 12653.620391388273, 12743.299118548199, 12776.233425994471, 12803.359832008176, 12886.548970785978, 12919.289439080942, 7893.486923349964, 8031.357491400557, 8070.677172913018, 8071.07182674, 8088.595570221348, 9868.802623947337, 9931.900680993387, 9943.900560994298, 9980.301378903407, 10029.247806414402, 8278.048024477263, 8368.748937531884, 8417.957910210655, 8429.494230345954, 8528.104549022264, 8065.395095367753, 8163.183681631789, 8206.507609543447, 8212.260896519341, 8289.336853051862, 7617.428692847948, 7621.733239336597, 7649.464537482323, 7708.460407771627, 7725.5364678119495, 5149.459306772735, 5187.0, 5243.292155558951, 5246.527812496741, 5327.360716714019, 5098.694078355311, 5132.538071573576, 5133.563647090039, 5198.298229738937, 5290.206469029677], "value": 4714.760516200395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.75707369231, 349.11847584848255, 350.0880207060609, 351.7361978516113, 354.7587640404524, 5669.376368599456, 5688.459596338341, 5725.828225153252, 5733.340665823437, 5751.971240143798, 5348.224507446418, 5568.470995255454, 5591.1054231323, 5593.356763972144, 5594.076977298751, 4152.252594532983, 4177.060161463677, 4180.331147016478, 4180.414741936132, 4200.894977625553, 4132.690048246379, 4160.45914031177, 4172.16556688662, 4194.538600753915, 4203.47456567929, 4062.7156099073077, 4099.200655872106, 4110.7328023678365, 4123.9381409278885, 4125.587441255881, 3993.5207775067033, 4076.694247931402, 4114.403411505315, 4127.422160897477, 4152.792360381969, 3581.5881173665102, 3581.982090089533, 3584.9283014339826, 3586.3006713690843, 3605.56733192016, 5353.705546194958, 5413.756380962866, 5445.646033007842, 5451.318585176853, 5457.863553411157, 4738.739369334685, 4851.932574833566, 4892.290617860391, 4896.706197628154, 4920.532549407801, 5900.970495147497, 5907.232059832238, 5915.674637894914, 5933.7329820157765, 5943.592844897823, 3997.300472417315, 4000.819963101638, 4006.8196931137877, 4026.375911733686, 4047.736897101679, 6008.729607167688, 6109.083637454373, 6283.937160628381, 6285.214348206474, 6287.685615719196, 4048.473698419187, 4078.592140785957, 4082.4284600156, 4083.91832163361, 4087.1621317629815, 4130.050088479667, 4134.421181034658, 4138.275801734684, 4141.751494910315, 4144.357624568197, 4849.660523763303, 4951.653384263139, 5067.189249720101, 5084.669496482717, 5099.158638824627, 5972.850678733066, 5975.7609695612555, 5984.670843103671, 5992.970035149883, 6016.939830601726, 1576.0937460959994, 1584.5800862771375, 1612.8306527814646, 1613.0321806915756, 1615.2327644368831, 1117.1453837814079, 1120.1934607082887, 1143.1197977557235, 1149.6665966869514, 1150.183369807446, 5042.672226305279, 5078.0, 5113.233015047773, 5119.48805119485, 5121.718305493234, 6106.297775755756, 6109.8472538186015, 6112.51099912006, 6122.969385153135, 6170.56806023583, 4966.428860681068, 5012.498750125032, 5051.898962020739, 5056.696598204119, 5087.516685914832, 3214.51782232667, 3241.594800649919, 3257.7719559631073, 3258.9837050814594, 3263.0, 5622.297212848394, 5632.436756324335, 5664.943350566524, 5670.432956704297, 5675.602707810496, 6041.78853740117, 6225.315215326311, 6268.843278917972, 6302.401271879256, 6316.715747791361, 5641.435856414326, 5665.518430933417, 5680.715964201773, 5686.459786320293, 5700.087985922232, 6039.36937027009, 6065.72704228302, 6119.908201376894, 6123.581185431819, 6205.720742566505, 5876.324222714443, 6048.366940926014, 6064.181335519825, 6072.969635151972, 6076.1493390924425, 5604.85987850307, 5614.550835933197, 5634.915081419901, 5653.943460565341, 5675.7445914933105, 4104.199681062164, 4107.67138628903, 4121.979390103031, 4135.1316223593385, 4145.502539695256, 5992.520598352208, 6066.332703402534, 6143.6928153593035, 6160.199174107337, 6174.845628859135, 5929.6145750526075, 5959.940400596112, 5960.612560183574, 5965.940340596539, 5979.521638269015, 5765.365809761007, 5930.52555795544, 5944.0489521678055, 5969.283685957713, 5981.970090149522, 5795.971020144872, 5810.506106980785, 5829.53363730892, 5852.619579727304, 5881.529477641693, 5992.940070599238, 5994.190784244071, 6017.699115044318, 6020.698965051817, 6049.153118563317, 5674.404187560247, 5755.366909639853, 5799.449052340021, 5811.360750317546, 5845.444682755132], "value": 4410.115292564718, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.6995456976219, 700.4046560423637, 715.5992644119293, 722.9638518074095, 740.2042803985717, 11697.766044679101, 12316.753664926697, 13277.734445311087, 13872.237026963512, 13973.650658733537, 14696.853031469682, 15124.546263612081, 15210.543683689479, 15304.387824487036, 15321.540353789376, 875.0549406640829, 890.4256754393413, 891.9687810926619, 893.9642414303453, 894.66897248018, 898.1736802142045, 899.6041741633693, 899.7660608241839, 900.1763386501343, 900.6127365232948, 918.7335672654922, 930.6044930904338, 931.2177770672633, 932.4172392254841, 932.5570354081791, 921.5624672740597, 929.5677509957902, 934.700895713372, 935.2845073518764, 935.8642996765465, 668.5353679192963, 668.6389349751129, 668.8160755792147, 669.5748199893054, 670.8289386206535, 14924.403023879031, 15002.62493437662, 15107.773383399257, 15143.697126057526, 15158.166300853449, 14337.71324573513, 14880.404783808635, 14880.776788348183, 15063.698726025528, 15070.246487675573, 12377.133600647958, 12501.312427816467, 12677.302748348837, 12677.683057923534, 12787.488500459971, 2831.9716802831913, 2833.546632538784, 2838.977967931543, 2854.7716182705335, 2865.8567071646335, 72039.0, 73904.63047684728, 74925.25074749238, 75923.24076759272, 78379.0, 11765.176437649454, 12113.576024839089, 12136.393180340947, 12174.391280435942, 12243.0, 23895.0, 24171.637425438632, 24258.51482970331, 24709.258722238363, 24856.502869942862, 6366.6180029198395, 6388.744450222032, 6396.360363963567, 6415.935840641534, 6425.036244563353, 3009.5335223040465, 3067.3098552825436, 3083.367909578531, 3084.799488033271, 3099.628044634658, 3600.261946301002, 3655.3420384330543, 3678.2459595782802, 3698.7041036716996, 3701.5188025556504, 708.5783958544657, 717.3579646216607, 722.5772922840198, 725.0357025156529, 753.2806170107534, 564.8277275431033, 572.6020415810972, 593.8841925824423, 596.1594152245314, 604.1119554255238, 9835.591292723273, 10304.536295866705, 10415.270931034755, 10661.147108231324, 10704.411257380918, 547.3295213363598, 551.2613098448098, 555.1783360626296, 557.4704031170323, 561.3432284227464, 4809.735464549413, 4817.590504807061, 4868.41579010521, 4928.778204980785, 5024.397072351341, 85807.57096214479, 86678.0, 87339.0, 88638.55680721556, 89022.10977890268, 72176.27823721801, 73128.63435682788, 73584.63207683928, 74344.62827685828, 74616.25383746202, 57310.71344643167, 58457.0, 61644.383556164765, 62088.0, 62508.37491625026, 10370.740731481472, 10434.947825260826, 10784.89215107839, 10871.728206794893, 11054.778904422028, 27114.864425677744, 27507.862460687567, 27957.161285161477, 28151.0, 29035.854820725763, 35077.64922350744, 35176.0, 52123.0, 53405.732971334895, 53567.73216133894, 13276.07067505304, 13423.0, 13472.0, 13485.258310792608, 13615.115017523829, 15386.769198461809, 15448.845511544741, 16174.433894813625, 16311.59221019484, 16687.415940442028, 15258.31337589789, 15404.0, 15474.303656335267, 15499.457518987232, 15725.135117568643, 14198.858011419754, 14703.632409189428, 15011.0, 15012.399504020153, 15165.772513412528, 14126.43494260218, 14903.180325082225, 15114.773278401053, 15204.695906082037, 15465.613359666098, 11510.424478776195, 11523.769524609294, 11570.710732231424, 11691.707707307385, 12054.879451205376, 11802.35087070185, 12246.755064898474, 12264.938675306566, 12376.786931577988, 12450.377481125726], "value": 7149.110165476226, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 110.0, 111.0, 110.0, 111.0, 111.0, 110.0, 110.0, 112.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 121.0, 171.0, 129.0, 124.0, 129.0, 124.0, 153.0, 125.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 164.0, 165.0, 168.0, 165.0, 168.0, 162.0, 168.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 116.0, 76.0, 75.0, 74.0, 74.0, 102.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 107.0, 84.0, 84.0, 83.0, 97.0, 83.0, 84.0, 108.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 114.0, 114.0, 114.0, 115.0, 113.0, 115.0, 115.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 151.0, 151.0, 150.0, 150.0, 149.0, 150.0, 151.0, 149.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 59.0, 54.0, 55.0, 56.0, 54.0, 54.0, 55.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 57.0, 57.0, 57.0, 57.0, 57.0, 56.0, 56.0], "value": 56.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 95.0, 95.0, 87.0, 87.0, 88.0, 123.0, 88.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 133.0, 138.0, 133.0, 133.0, 132.0, 137.0, 153.0, 140.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 199.0, 199.0, 196.0, 198.0, 191.0, 194.0, 190.0, 189.0, 193.0], "value": 193.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 76.0, 73.0, 76.0, 72.0, 74.0, 72.0, 70.0, 71.0], "value": 72.9, "unit": "score"}]}]} 01:54:16 INFO - Return code: 0 01:54:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:54:16 INFO - # TBPL SUCCESS # 01:54:16 INFO - Running post-action listener: _resource_record_post_action 01:54:16 INFO - [mozharness: 2017-05-27 08:54:16.641516Z] Finished run-tests step (success) 01:54:16 INFO - Running post-run listener: _resource_record_post_run 01:54:16 INFO - Total resource usage - Wall time: 1255s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408276992; Read time: 16; Write time: 299600 01:54:16 INFO - TinderboxPrint: CPU usage
12.7% 01:54:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 01:54:16 INFO - TinderboxPrint: I/O write bytes / time
408,276,992 / 299,600 01:54:16 INFO - TinderboxPrint: CPU idle
8,746.1 (87.3%) 01:54:16 INFO - TinderboxPrint: CPU user
1,219.3 (12.2%) 01:54:16 INFO - TinderboxPrint: Swap in / out
0 / 0 01:54:16 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 95215616; Read time: 0; Write time: 90280 01:54:16 INFO - run-tests - Wall time: 1239s; CPU: 13.0%; Read bytes: 0; Write bytes: 313061376; Read time: 0; Write time: 209320 01:54:17 INFO - Running post-run listener: _upload_blobber_files 01:54:17 INFO - Blob upload gear active. 01:54:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:54:17 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 01:54:17 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'] 01:54:17 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 01:54:17 INFO - (blobuploader) - INFO - Open directory for files ... 01:54:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:54:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:54:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:18 INFO - (blobuploader) - INFO - Done attempting. 01:54:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:54:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:54:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:19 INFO - (blobuploader) - INFO - Done attempting. 01:54:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:54:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:25 INFO - (blobuploader) - INFO - Done attempting. 01:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:54:26 INFO - (blobuploader) - INFO - Done attempting. 01:54:26 INFO - (blobuploader) - INFO - Iteration through files over. 01:54:26 INFO - Return code: 0 01:54:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:54:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:54:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0afd145acbb84015d211d2b5ebbbc69d630ce15601b23c9a1b84eb8c691917e35e56e57b62cc1c2fe2216b41784f1bb35b29187673f2fc31e9dbfeb10cf25d0b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08191fc38f115eaa5eb5691d5d7e529d5b0324659f18c826e28a1a0fbd10719a9d8e6de3217355fa29073a7c2ee4075d822d8365764a31c4c5be17fc68e4fdd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2c7b40ac8fa197d35c91c28f92e6fa8725d4f03bb2c10ddb6c2f966764d3a7e7b7f0aff52b1e0a94ae51e3a8db27d935abd8bade14a2f0edd6666848a0f988f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a6b56ca6eae8fa2eb2576d9d45697fc5a7c09a7aad17c0a345fbdeab94507d40b341e3c6a7a000e3480e5e23aa93f138bfb3863bffc0455df0c1701f7177a6"} 01:54:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:54:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:54:26 INFO - Contents: 01:54:26 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0afd145acbb84015d211d2b5ebbbc69d630ce15601b23c9a1b84eb8c691917e35e56e57b62cc1c2fe2216b41784f1bb35b29187673f2fc31e9dbfeb10cf25d0b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08191fc38f115eaa5eb5691d5d7e529d5b0324659f18c826e28a1a0fbd10719a9d8e6de3217355fa29073a7c2ee4075d822d8365764a31c4c5be17fc68e4fdd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2c7b40ac8fa197d35c91c28f92e6fa8725d4f03bb2c10ddb6c2f966764d3a7e7b7f0aff52b1e0a94ae51e3a8db27d935abd8bade14a2f0edd6666848a0f988f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a6b56ca6eae8fa2eb2576d9d45697fc5a7c09a7aad17c0a345fbdeab94507d40b341e3c6a7a000e3480e5e23aa93f138bfb3863bffc0455df0c1701f7177a6"} 01:54:26 INFO - Running post-run listener: copy_logs_to_upload_dir 01:54:26 INFO - Copying logs to upload dir... 01:54:26 INFO - mkdir: /builds/slave/test/build/upload/logs 01:54:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1317.674025 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2017-05-27 01:54:26.144853) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 01:54:26.148136) ========= 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=a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905 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/0afd145acbb84015d211d2b5ebbbc69d630ce15601b23c9a1b84eb8c691917e35e56e57b62cc1c2fe2216b41784f1bb35b29187673f2fc31e9dbfeb10cf25d0b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08191fc38f115eaa5eb5691d5d7e529d5b0324659f18c826e28a1a0fbd10719a9d8e6de3217355fa29073a7c2ee4075d822d8365764a31c4c5be17fc68e4fdd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2c7b40ac8fa197d35c91c28f92e6fa8725d4f03bb2c10ddb6c2f966764d3a7e7b7f0aff52b1e0a94ae51e3a8db27d935abd8bade14a2f0edd6666848a0f988f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a6b56ca6eae8fa2eb2576d9d45697fc5a7c09a7aad17c0a345fbdeab94507d40b341e3c6a7a000e3480e5e23aa93f138bfb3863bffc0455df0c1701f7177a6"} build_url:https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011337 build_url: 'https://queue.taskcluster.net/v1/task/OzHpRs25QTOGeaoTcyDY-w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0afd145acbb84015d211d2b5ebbbc69d630ce15601b23c9a1b84eb8c691917e35e56e57b62cc1c2fe2216b41784f1bb35b29187673f2fc31e9dbfeb10cf25d0b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08191fc38f115eaa5eb5691d5d7e529d5b0324659f18c826e28a1a0fbd10719a9d8e6de3217355fa29073a7c2ee4075d822d8365764a31c4c5be17fc68e4fdd2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2c7b40ac8fa197d35c91c28f92e6fa8725d4f03bb2c10ddb6c2f966764d3a7e7b7f0aff52b1e0a94ae51e3a8db27d935abd8bade14a2f0edd6666848a0f988f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a6b56ca6eae8fa2eb2576d9d45697fc5a7c09a7aad17c0a345fbdeab94507d40b341e3c6a7a000e3480e5e23aa93f138bfb3863bffc0455df0c1701f7177a6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 01:54:26.173115) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:54:26.173436) ========= 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=a0d2d2de893abaa8702a9954000001a7-1495856678.466162-2125065905 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 01:54:26.255537) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 01:54:26.255877) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 01:54:26.256192) ========= ========= Total master_lag: 0.17 =========