builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-061 starttime: 1494557120.29 results: success (0) revision: ad45e59c7683a1354c8b73566f653d515f8d9a42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.287326) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.287771) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.288040) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.302994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.303370) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 19:45:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-05-11 19:45:21 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112806 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.429748) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.430064) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016190 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.483149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:20.486786) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ad45e59c7683a1354c8b73566f653d515f8d9a42 --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 ad45e59c7683a1354c8b73566f653d515f8d9a42 --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=516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 19:45:21,280 truncating revision to first 12 chars 2017-05-11 19:45:21,280 Setting DEBUG logging. 2017-05-11 19:45:21,280 attempt 1/10 2017-05-11 19:45:21,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ad45e59c7683?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 19:45:21,590 unpacking tar archive at: mozilla-central-ad45e59c7683/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636229 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:21.137231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:21.140651) ========= script_repo_revision: ad45e59c7683a1354c8b73566f653d515f8d9a42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:21.141064) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:21.141341) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 19:45:21.152509) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-05-11 19:45:21.152914) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:45:22 INFO - MultiFileLogger online at 20170511 19:45:22 in /builds/slave/test 19:45:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:45:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:45:22 INFO - {'append_to_log': False, 19:45:22 INFO - 'base_work_dir': '/builds/slave/test', 19:45:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:45:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:45:22 INFO - 'branch': 'Firefox-Non-PGO', 19:45:22 INFO - 'buildbot_json_path': 'buildprops.json', 19:45:22 INFO - 'config_files': ('talos/linux_config.py',), 19:45:22 INFO - 'default_actions': ('clobber', 19:45:22 INFO - 'read-buildbot-config', 19:45:22 INFO - 'download-and-extract', 19:45:22 INFO - 'populate-webroot', 19:45:22 INFO - 'create-virtualenv', 19:45:22 INFO - 'install', 19:45:22 INFO - 'run-tests'), 19:45:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:45:22 INFO - 'download_minidump_stackwalk': True, 19:45:22 INFO - 'download_symbols': 'ondemand', 19:45:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:45:22 INFO - 'tooltool.py': '/tools/tooltool.py', 19:45:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:45:22 INFO - '/tools/misc-python/virtualenv.py')}, 19:45:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:45:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:45:22 INFO - 'gecko_profile': False, 19:45:22 INFO - 'gecko_profile_interval': 0, 19:45:22 INFO - 'installer_path': 'installer.exe', 19:45:22 INFO - 'log_level': 'info', 19:45:22 INFO - 'log_name': 'talos', 19:45:22 INFO - 'log_to_console': True, 19:45:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:45:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:45:22 INFO - 'opt_config_files': (), 19:45:22 INFO - 'pip_index': False, 19:45:22 INFO - 'suite': 'dromaeojs', 19:45:22 INFO - 'system_bits': '32', 19:45:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:45:22 INFO - 'title': 'talos-linux64-ix-061', 19:45:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:45:22 INFO - 'use_talos_json': True, 19:45:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:45:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:45:22 INFO - 'work_dir': 'build'} 19:45:22 INFO - [mozharness: 2017-05-12 02:45:22.041975Z] Running clobber step. 19:45:22 INFO - Running pre-action listener: _resource_record_pre_action 19:45:22 INFO - Running main action method: clobber 19:45:22 INFO - rmtree: /builds/slave/test/build 19:45:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:45:22 INFO - Running post-action listener: _resource_record_post_action 19:45:22 INFO - [mozharness: 2017-05-12 02:45:22.974424Z] Finished clobber step (success) 19:45:22 INFO - [mozharness: 2017-05-12 02:45:22.974524Z] Running read-buildbot-config step. 19:45:22 INFO - Running pre-action listener: _resource_record_pre_action 19:45:22 INFO - Running main action method: read_buildbot_config 19:45:22 INFO - Using buildbot properties: 19:45:22 INFO - { 19:45:22 INFO - "project": "", 19:45:22 INFO - "product": "firefox", 19:45:22 INFO - "who": "ihsiao@mozilla.com", 19:45:22 INFO - "installer_path": "public/build/target.tar.bz2", 19:45:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:45:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:45:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 19:45:22 INFO - "stage_platform": "linux64", 19:45:22 INFO - "basedir": "/builds/slave/test", 19:45:22 INFO - "buildnumber": 595, 19:45:22 INFO - "platform": "ubuntu64_hw", 19:45:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:45:22 INFO - "slavebuilddir": "test", 19:45:22 INFO - "taskId": "O6Y5aYVzTEyhD-9lkezu9A", 19:45:22 INFO - "branch": "mozilla-central", 19:45:22 INFO - "script_repo_revision": "production", 19:45:22 INFO - "revision": "ad45e59c7683a1354c8b73566f653d515f8d9a42", 19:45:22 INFO - "slavename": "talos-linux64-ix-061", 19:45:22 INFO - "repo_path": "mozilla-central" 19:45:22 INFO - } 19:45:22 INFO - Finding installer, test and symbols from parent task. 19:45:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6Y5aYVzTEyhD-9lkezu9A'}, attempt #1 19:45:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6Y5aYVzTEyhD-9lkezu9A'}, attempt #1 19:45:23 INFO - Task dependencies: Y47ftADDTcOBFe5B9Lku6w 19:45:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w'}, attempt #1 19:45:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2 19:45:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/test_packages.json 19:45:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.crashreporter-symbols.zip 19:45:24 INFO - Running post-action listener: _resource_record_post_action 19:45:24 INFO - [mozharness: 2017-05-12 02:45:24.294923Z] Finished read-buildbot-config step (success) 19:45:24 INFO - [mozharness: 2017-05-12 02:45:24.295140Z] Running download-and-extract step. 19:45:24 INFO - Running pre-action listener: _resource_record_pre_action 19:45:24 INFO - Running main action method: download_and_extract 19:45:24 INFO - mkdir: /builds/slave/test/build/tests 19:45:24 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')]} 19:45:24 INFO - trying https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.test_packages.json 19:45:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:45:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:45:24 INFO - Downloaded 986 bytes. 19:45:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:45:24 INFO - Using the following test package requirements: 19:45:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:45:24 INFO - u'common': [u'target.common.tests.zip'], 19:45:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:45:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:45:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:45:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:45:24 INFO - u'mozbase': [u'target.common.tests.zip'], 19:45:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:45:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:45:24 INFO - u'web-platform': [u'target.common.tests.zip', 19:45:24 INFO - u'target.web-platform.tests.zip'], 19:45:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:45:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:45:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.common.tests.zip 19:45:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:45:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.common.tests.zip into memory 19:45:25 INFO - Content-Length response header: 58597240 19:45:25 INFO - Bytes received: 58597240 19:45:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:45:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.common.tests.zip 19:45:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:45:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.common.tests.zip into memory 19:45:36 INFO - Content-Length response header: 58597240 19:45:36 INFO - Bytes received: 58597240 19:45:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.talos.tests.zip 19:45:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:45:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.talos.tests.zip into memory 19:45:48 INFO - Content-Length response header: 14409411 19:45:48 INFO - Bytes received: 14409411 19:45:48 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')]} 19:45:48 INFO - trying https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2 19:45:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:45:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:45:49 INFO - Downloaded 63292642 bytes. 19:45:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2 19:45:49 INFO - mkdir: /builds/slave/test/properties 19:45:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:45:49 INFO - Writing to file /builds/slave/test/properties/build_url 19:45:49 INFO - Contents: 19:45:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2 19:45:49 INFO - Running post-action listener: _resource_record_post_action 19:45:49 INFO - Running post-action listener: set_extra_try_arguments 19:45:49 INFO - [mozharness: 2017-05-12 02:45:49.846669Z] Finished download-and-extract step (success) 19:45:49 INFO - [mozharness: 2017-05-12 02:45:49.846765Z] Running populate-webroot step. 19:45:49 INFO - Running pre-action listener: _resource_record_pre_action 19:45:49 INFO - Running main action method: populate_webroot 19:45:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:45:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:45:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:45:49 INFO - '--tppagecycles', 19:45:49 INFO - '7'], 19:45:49 INFO - 'tests': ['tsvgm']}}, 19:45:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:45:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:45:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:45:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:45:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:45:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:45:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['damp', 'tps']}, 19:45:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'tests': ['damp', 'tps']}, 19:45:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['dromaeo_dom']}, 19:45:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:45:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:45:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:45:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['a11yr', 19:45:49 INFO - 'ts_paint', 19:45:49 INFO - 'tpaint', 19:45:49 INFO - 'sessionrestore', 19:45:49 INFO - 'sessionrestore_no_auto_restore', 19:45:49 INFO - 'tabpaint']}, 19:45:49 INFO - 'other-e10s': {'tests': ['a11yr', 19:45:49 INFO - 'ts_paint', 19:45:49 INFO - 'tpaint', 19:45:49 INFO - 'sessionrestore', 19:45:49 INFO - 'sessionrestore_no_auto_restore', 19:45:49 INFO - 'tabpaint']}, 19:45:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:45:49 INFO - 'ts_paint', 19:45:49 INFO - 'tpaint', 19:45:49 INFO - 'sessionrestore', 19:45:49 INFO - 'sessionrestore_no_auto_restore', 19:45:49 INFO - 'tabpaint']}, 19:45:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:45:49 INFO - 'ts_paint', 19:45:49 INFO - 'tpaint', 19:45:49 INFO - 'sessionrestore', 19:45:49 INFO - 'sessionrestore_no_auto_restore', 19:45:49 INFO - 'tabpaint']}, 19:45:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['a11yr', 19:45:49 INFO - 'ts_paint', 19:45:49 INFO - 'tpaint', 19:45:49 INFO - 'sessionrestore', 19:45:49 INFO - 'sessionrestore_no_auto_restore', 19:45:49 INFO - 'tabpaint']}, 19:45:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['a11yr', 19:45:49 INFO - 'ts_paint', 19:45:49 INFO - 'tpaint', 19:45:49 INFO - 'sessionrestore', 19:45:49 INFO - 'sessionrestore_no_auto_restore', 19:45:49 INFO - 'tabpaint']}, 19:45:49 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:45:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:45:49 INFO - 'bloom_basic_ref']}, 19:45:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['tsvgx', 19:45:49 INFO - 'tsvgr_opacity', 19:45:49 INFO - 'tart', 19:45:49 INFO - 'tscrollx', 19:45:49 INFO - 'cart', 19:45:49 INFO - 'tsvg_static']}, 19:45:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:45:49 INFO - 'tsvgr_opacity', 19:45:49 INFO - 'tart', 19:45:49 INFO - 'tscrollx', 19:45:49 INFO - 'cart', 19:45:49 INFO - 'tsvg_static']}, 19:45:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'talos_options': ['--disable-e10s'], 19:45:49 INFO - 'tests': ['tp5o']}, 19:45:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:45:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'talos_options': ['--disable-e10s', 19:45:49 INFO - '--xperf_path', 19:45:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:45:49 INFO - 'tests': ['tp5n']}, 19:45:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:45:49 INFO - 'talos_options': ['--xperf_path', 19:45:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:45:49 INFO - 'tests': ['tp5n']}}, 19:45:49 INFO - 'talos.zip': {'path': '', 19:45:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:45:49 INFO - Running post-action listener: _resource_record_post_action 19:45:49 INFO - [mozharness: 2017-05-12 02:45:49.860359Z] Finished populate-webroot step (success) 19:45:49 INFO - [mozharness: 2017-05-12 02:45:49.860448Z] Running create-virtualenv step. 19:45:49 INFO - Running pre-action listener: _resource_record_pre_action 19:45:49 INFO - Running main action method: create_virtualenv 19:45:49 INFO - Creating virtualenv /builds/slave/test/build/venv 19:45:49 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 19:45:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:45:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:45:49 INFO - Using real prefix '/usr' 19:45:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:45:50 INFO - Installing distribute.............................................................................................................................................................................................done. 19:45:52 INFO - Installing pip.................done. 19:45:52 INFO - Return code: 0 19:45:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:45:52 INFO - Reading from file tmpfile_stdout 19:45:52 INFO - Output received: 19:45:52 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) 19:45:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:45:52 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')]} 19:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:52 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')]} 19:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:52 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:45:52 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 19:45:52 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 19:45:52 INFO - Using env: {'DISPLAY': ':0', 19:45:52 INFO - 'HOME': '/home/cltbld', 19:45:52 INFO - 'LANG': 'en_US.UTF-8', 19:45:52 INFO - 'LANGUAGE': 'en_US:en', 19:45:52 INFO - 'LOGNAME': 'cltbld', 19:45:52 INFO - 'MAIL': '/var/mail/cltbld', 19:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:52 INFO - 'MOZ_NO_REMOTE': '1', 19:45:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:52 INFO - 'NO_EM_RESTART': '1', 19:45:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:52 INFO - 'PWD': '/builds/slave/test', 19:45:52 INFO - 'SHELL': '/bin/bash', 19:45:52 INFO - 'SHLVL': '1', 19:45:52 INFO - 'TERM': 'linux', 19:45:52 INFO - 'TMOUT': '86400', 19:45:52 INFO - 'USER': 'cltbld', 19:45:52 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:52 INFO - '_': '/tools/buildbot/bin/python'} 19:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:52 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 19:45:52 INFO - Cleaning up... 19:45:52 INFO - Return code: 0 19:45:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:45:52 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')]} 19:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:52 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')]} 19:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:52 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:45:52 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 19:45:52 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 19:45:52 INFO - Using env: {'DISPLAY': ':0', 19:45:52 INFO - 'HOME': '/home/cltbld', 19:45:52 INFO - 'LANG': 'en_US.UTF-8', 19:45:52 INFO - 'LANGUAGE': 'en_US:en', 19:45:52 INFO - 'LOGNAME': 'cltbld', 19:45:52 INFO - 'MAIL': '/var/mail/cltbld', 19:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:52 INFO - 'MOZ_NO_REMOTE': '1', 19:45:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:52 INFO - 'NO_EM_RESTART': '1', 19:45:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:52 INFO - 'PWD': '/builds/slave/test', 19:45:52 INFO - 'SHELL': '/bin/bash', 19:45:52 INFO - 'SHLVL': '1', 19:45:52 INFO - 'TERM': 'linux', 19:45:52 INFO - 'TMOUT': '86400', 19:45:52 INFO - 'USER': 'cltbld', 19:45:52 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:52 INFO - '_': '/tools/buildbot/bin/python'} 19:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:53 INFO - Downloading/unpacking psutil>=3.1.1 19:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:45:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:45:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:45:54 INFO - Installing collected packages: psutil 19:45:54 INFO - Running setup.py install for psutil 19:45:54 INFO - building 'psutil._psutil_linux' extension 19:45:54 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 19:45:54 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 19:45:54 INFO - building 'psutil._psutil_posix' extension 19:45:54 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 19:45:54 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 19:45:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:45:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:45:54 INFO - Successfully installed psutil 19:45:54 INFO - Cleaning up... 19:45:54 INFO - Return code: 0 19:45:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:45:54 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')]} 19:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:54 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')]} 19:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:54 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:45:54 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 19:45:54 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 19:45:54 INFO - Using env: {'DISPLAY': ':0', 19:45:54 INFO - 'HOME': '/home/cltbld', 19:45:54 INFO - 'LANG': 'en_US.UTF-8', 19:45:54 INFO - 'LANGUAGE': 'en_US:en', 19:45:54 INFO - 'LOGNAME': 'cltbld', 19:45:54 INFO - 'MAIL': '/var/mail/cltbld', 19:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:54 INFO - 'MOZ_NO_REMOTE': '1', 19:45:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:54 INFO - 'NO_EM_RESTART': '1', 19:45:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:54 INFO - 'PWD': '/builds/slave/test', 19:45:54 INFO - 'SHELL': '/bin/bash', 19:45:54 INFO - 'SHLVL': '1', 19:45:54 INFO - 'TERM': 'linux', 19:45:54 INFO - 'TMOUT': '86400', 19:45:54 INFO - 'USER': 'cltbld', 19:45:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:54 INFO - '_': '/tools/buildbot/bin/python'} 19:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:45:55 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 19:45:55 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 19:45:55 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 19:45:55 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 19:45:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:45:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:45:56 INFO - Installing collected packages: mozsystemmonitor 19:45:56 INFO - Running setup.py install for mozsystemmonitor 19:45:56 INFO - Successfully installed mozsystemmonitor 19:45:56 INFO - Cleaning up... 19:45:56 INFO - Return code: 0 19:45:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:56 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:45:56 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 19:45:56 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 19:45:56 INFO - Using env: {'DISPLAY': ':0', 19:45:56 INFO - 'HOME': '/home/cltbld', 19:45:56 INFO - 'LANG': 'en_US.UTF-8', 19:45:56 INFO - 'LANGUAGE': 'en_US:en', 19:45:56 INFO - 'LOGNAME': 'cltbld', 19:45:56 INFO - 'MAIL': '/var/mail/cltbld', 19:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:56 INFO - 'MOZ_NO_REMOTE': '1', 19:45:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:56 INFO - 'NO_EM_RESTART': '1', 19:45:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:56 INFO - 'PWD': '/builds/slave/test', 19:45:56 INFO - 'SHELL': '/bin/bash', 19:45:56 INFO - 'SHLVL': '1', 19:45:56 INFO - 'TERM': 'linux', 19:45:56 INFO - 'TMOUT': '86400', 19:45:56 INFO - 'USER': 'cltbld', 19:45:56 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:56 INFO - '_': '/tools/buildbot/bin/python'} 19:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:56 INFO - Downloading/unpacking jsonschema==2.5.1 19:45:56 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 19:45:56 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 19:45:56 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 19:45:56 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 19:45:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:45:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:57 INFO - Downloading functools32-3.2.3-2.tar.gz 19:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:45:57 INFO - warning: no files found matching '*.txt' 19:45:57 INFO - no previously-included directories found matching 'build' 19:45:57 INFO - no previously-included directories found matching 'dist' 19:45:57 INFO - no previously-included directories found matching '.git*' 19:45:57 INFO - Installing collected packages: jsonschema, functools32 19:45:57 INFO - Running setup.py install for functools32 19:45:57 INFO - warning: no files found matching '*.txt' 19:45:57 INFO - no previously-included directories found matching 'build' 19:45:57 INFO - no previously-included directories found matching 'dist' 19:45:57 INFO - no previously-included directories found matching '.git*' 19:45:57 INFO - Successfully installed jsonschema functools32 19:45:57 INFO - Cleaning up... 19:45:57 INFO - Return code: 0 19:45:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:58 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:45:58 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 19:45:58 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 19:45:58 INFO - Using env: {'DISPLAY': ':0', 19:45:58 INFO - 'HOME': '/home/cltbld', 19:45:58 INFO - 'LANG': 'en_US.UTF-8', 19:45:58 INFO - 'LANGUAGE': 'en_US:en', 19:45:58 INFO - 'LOGNAME': 'cltbld', 19:45:58 INFO - 'MAIL': '/var/mail/cltbld', 19:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:58 INFO - 'MOZ_NO_REMOTE': '1', 19:45:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:58 INFO - 'NO_EM_RESTART': '1', 19:45:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:58 INFO - 'PWD': '/builds/slave/test', 19:45:58 INFO - 'SHELL': '/bin/bash', 19:45:58 INFO - 'SHLVL': '1', 19:45:58 INFO - 'TERM': 'linux', 19:45:58 INFO - 'TMOUT': '86400', 19:45:58 INFO - 'USER': 'cltbld', 19:45:58 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:58 INFO - '_': '/tools/buildbot/bin/python'} 19:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:45:58 INFO - Cleaning up... 19:45:58 INFO - Return code: 0 19:45:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:45:58 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')]} 19:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:45:58 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')]} 19:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:45:58 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:45:58 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 19:45:58 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 19:45:58 INFO - Using env: {'DISPLAY': ':0', 19:45:58 INFO - 'HOME': '/home/cltbld', 19:45:58 INFO - 'LANG': 'en_US.UTF-8', 19:45:58 INFO - 'LANGUAGE': 'en_US:en', 19:45:58 INFO - 'LOGNAME': 'cltbld', 19:45:58 INFO - 'MAIL': '/var/mail/cltbld', 19:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:58 INFO - 'MOZ_NO_REMOTE': '1', 19:45:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:45:58 INFO - 'NO_EM_RESTART': '1', 19:45:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:58 INFO - 'PWD': '/builds/slave/test', 19:45:58 INFO - 'SHELL': '/bin/bash', 19:45:58 INFO - 'SHLVL': '1', 19:45:58 INFO - 'TERM': 'linux', 19:45:58 INFO - 'TMOUT': '86400', 19:45:58 INFO - 'USER': 'cltbld', 19:45:58 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:58 INFO - '_': '/tools/buildbot/bin/python'} 19:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:45:58 INFO - Downloading/unpacking blobuploader==1.2.4 19:45:58 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 19:45:58 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 19:45:58 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 19:45:58 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 19:45:59 INFO - Downloading blobuploader-1.2.4.tar.gz 19:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:45:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:45:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:00 INFO - Downloading docopt-0.6.1.tar.gz 19:46:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:46:00 INFO - Installing collected packages: blobuploader, requests, docopt 19:46:00 INFO - Running setup.py install for blobuploader 19:46:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:46:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:46:00 INFO - Running setup.py install for requests 19:46:00 INFO - Running setup.py install for docopt 19:46:00 INFO - Successfully installed blobuploader requests docopt 19:46:00 INFO - Cleaning up... 19:46:00 INFO - Return code: 0 19:46:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:46: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')]} 19:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46: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')]} 19:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:00 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:46:00 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 19:46:00 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 19:46:00 INFO - Using env: {'DISPLAY': ':0', 19:46:00 INFO - 'HOME': '/home/cltbld', 19:46:00 INFO - 'LANG': 'en_US.UTF-8', 19:46:00 INFO - 'LANGUAGE': 'en_US:en', 19:46:00 INFO - 'LOGNAME': 'cltbld', 19:46:00 INFO - 'MAIL': '/var/mail/cltbld', 19:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:00 INFO - 'MOZ_NO_REMOTE': '1', 19:46:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:00 INFO - 'NO_EM_RESTART': '1', 19:46:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:00 INFO - 'PWD': '/builds/slave/test', 19:46:00 INFO - 'SHELL': '/bin/bash', 19:46:00 INFO - 'SHLVL': '1', 19:46:00 INFO - 'TERM': 'linux', 19:46:00 INFO - 'TMOUT': '86400', 19:46:00 INFO - 'USER': 'cltbld', 19:46:00 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:00 INFO - '_': '/tools/buildbot/bin/python'} 19:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:46:00 INFO - Running setup.py (path:/tmp/pip-37AOTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:46:00 INFO - Running setup.py (path:/tmp/pip-Xg6bka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:46:01 INFO - Running setup.py (path:/tmp/pip-LGZPTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:46:01 INFO - Running setup.py (path:/tmp/pip-SoFt3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:46:01 INFO - Running setup.py (path:/tmp/pip-yAwoVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:46:01 INFO - Running setup.py (path:/tmp/pip-hceatE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:46:01 INFO - Running setup.py (path:/tmp/pip-FgUvZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:46:01 INFO - Running setup.py (path:/tmp/pip-RAXtTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:46:01 INFO - Running setup.py (path:/tmp/pip-GSN3ry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:46:01 INFO - Running setup.py (path:/tmp/pip-zD2pK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:46:01 INFO - Running setup.py (path:/tmp/pip-XzOuGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:46:01 INFO - Running setup.py (path:/tmp/pip-r2qEqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:46:01 INFO - Running setup.py (path:/tmp/pip-Dr0KKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:46:01 INFO - Running setup.py (path:/tmp/pip-7imKKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:01 INFO - Running setup.py (path:/tmp/pip-YHDbG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:46:01 INFO - Running setup.py (path:/tmp/pip-Mc2bLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:46:02 INFO - Running setup.py (path:/tmp/pip-apzPkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:46:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:46:02 INFO - Running setup.py install for manifestparser 19:46:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Running setup.py install for mozcrash 19:46:02 INFO - Running setup.py install for mozdebug 19:46:02 INFO - Running setup.py install for mozdevice 19:46:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Running setup.py install for mozfile 19:46:02 INFO - Running setup.py install for mozhttpd 19:46:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Running setup.py install for mozinfo 19:46:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Running setup.py install for mozInstall 19:46:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:46:02 INFO - Running setup.py install for mozleak 19:46:02 INFO - Running setup.py install for mozlog 19:46:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Running setup.py install for moznetwork 19:46:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Running setup.py install for mozprocess 19:46:03 INFO - Running setup.py install for mozprofile 19:46:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Running setup.py install for mozrunner 19:46:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Running setup.py install for mozscreenshot 19:46:03 INFO - Running setup.py install for moztest 19:46:03 INFO - Running setup.py install for mozversion 19:46:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:46:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:46:03 INFO - Cleaning up... 19:46:03 INFO - Return code: 0 19:46:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:46:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:03 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:46:03 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 19:46:03 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 19:46:03 INFO - Using env: {'DISPLAY': ':0', 19:46:03 INFO - 'HOME': '/home/cltbld', 19:46:03 INFO - 'LANG': 'en_US.UTF-8', 19:46:03 INFO - 'LANGUAGE': 'en_US:en', 19:46:03 INFO - 'LOGNAME': 'cltbld', 19:46:03 INFO - 'MAIL': '/var/mail/cltbld', 19:46:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:03 INFO - 'MOZ_NO_REMOTE': '1', 19:46:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:03 INFO - 'NO_EM_RESTART': '1', 19:46:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:03 INFO - 'PWD': '/builds/slave/test', 19:46:03 INFO - 'SHELL': '/bin/bash', 19:46:03 INFO - 'SHLVL': '1', 19:46:03 INFO - 'TERM': 'linux', 19:46:03 INFO - 'TMOUT': '86400', 19:46:03 INFO - 'USER': 'cltbld', 19:46:03 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:46:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:03 INFO - '_': '/tools/buildbot/bin/python'} 19:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:46:04 INFO - Running setup.py (path:/tmp/pip-cVopxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:46:04 INFO - Running setup.py (path:/tmp/pip-M816Fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:46:04 INFO - Running setup.py (path:/tmp/pip-2ZjCOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:46:04 INFO - Running setup.py (path:/tmp/pip-Wk8M8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:46:04 INFO - Running setup.py (path:/tmp/pip-LKEWHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:46:04 INFO - Running setup.py (path:/tmp/pip-hHBC1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:46:04 INFO - Running setup.py (path:/tmp/pip-GCEWCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:46:04 INFO - Running setup.py (path:/tmp/pip-KGTgb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:46:04 INFO - Running setup.py (path:/tmp/pip-enRzkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:46:04 INFO - Running setup.py (path:/tmp/pip-schxEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:46:04 INFO - Running setup.py (path:/tmp/pip-39YbX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:46:04 INFO - Running setup.py (path:/tmp/pip-yJH_7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:46:04 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)) 19:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:46:04 INFO - Running setup.py (path:/tmp/pip-if55pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:46:05 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)) 19:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:46:05 INFO - Running setup.py (path:/tmp/pip-w3WBJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:46:05 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)) 19:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:05 INFO - Running setup.py (path:/tmp/pip-Vsk6eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:05 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)) 19:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:46:05 INFO - Running setup.py (path:/tmp/pip-giO1ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:46:05 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)) 19:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:46:05 INFO - Running setup.py (path:/tmp/pip-P3GLUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:46:05 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)) 19:46:05 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)) 19:46:05 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)) 19:46:05 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)) 19:46:05 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:46:05 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:46:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:46: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 19:46: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 19:46: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 19:46: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 19:46:06 INFO - Downloading blessings-1.6.tar.gz 19:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:46:06 INFO - Installing collected packages: blessings 19:46:06 INFO - Running setup.py install for blessings 19:46:06 INFO - Successfully installed blessings 19:46:06 INFO - Cleaning up... 19:46:06 INFO - Return code: 0 19:46:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:46:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:46:06 INFO - Reading from file tmpfile_stdout 19:46:06 INFO - Current package versions: 19:46:06 INFO - argparse == 1.2.1 19:46:06 INFO - blessings == 1.6 19:46:06 INFO - blobuploader == 1.2.4 19:46:06 INFO - docopt == 0.6.1 19:46:06 INFO - functools32 == 3.2.3-2 19:46:06 INFO - jsonschema == 2.5.1 19:46:06 INFO - manifestparser == 1.1 19:46:06 INFO - mozInstall == 1.12 19:46:06 INFO - mozcrash == 1.0 19:46:06 INFO - mozdebug == 0.1 19:46:06 INFO - mozdevice == 0.50 19:46:06 INFO - mozfile == 1.2 19:46:06 INFO - mozhttpd == 0.7 19:46:06 INFO - mozinfo == 0.9 19:46:06 INFO - mozleak == 0.1 19:46:06 INFO - mozlog == 3.4 19:46:06 INFO - moznetwork == 0.27 19:46:06 INFO - mozprocess == 0.25 19:46:06 INFO - mozprofile == 0.28 19:46:06 INFO - mozrunner == 6.13 19:46:06 INFO - mozscreenshot == 0.1 19:46:06 INFO - mozsystemmonitor == 0.3 19:46:06 INFO - moztest == 0.8 19:46:06 INFO - mozversion == 1.4 19:46:06 INFO - psutil == 3.1.1 19:46:06 INFO - requests == 1.2.3 19:46:06 INFO - wsgiref == 0.1.2 19:46:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:46: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')]} 19:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46: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')]} 19:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:06 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 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:46:06 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 19:46:06 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 19:46:06 INFO - Using env: {'DISPLAY': ':0', 19:46:06 INFO - 'HOME': '/home/cltbld', 19:46:06 INFO - 'LANG': 'en_US.UTF-8', 19:46:06 INFO - 'LANGUAGE': 'en_US:en', 19:46:06 INFO - 'LOGNAME': 'cltbld', 19:46:06 INFO - 'MAIL': '/var/mail/cltbld', 19:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:06 INFO - 'MOZ_NO_REMOTE': '1', 19:46:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:06 INFO - 'NO_EM_RESTART': '1', 19:46:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:06 INFO - 'PWD': '/builds/slave/test', 19:46:06 INFO - 'SHELL': '/bin/bash', 19:46:06 INFO - 'SHLVL': '1', 19:46:06 INFO - 'TERM': 'linux', 19:46:06 INFO - 'TMOUT': '86400', 19:46:06 INFO - 'USER': 'cltbld', 19:46:06 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:06 INFO - '_': '/tools/buildbot/bin/python'} 19:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 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)) 19:46:07 INFO - Cleaning up... 19:46:07 INFO - Return code: 0 19:46:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1422a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', '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 19:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:46:07 INFO - Using env: {'DISPLAY': ':0', 19:46:07 INFO - 'HOME': '/home/cltbld', 19:46:07 INFO - 'LANG': 'en_US.UTF-8', 19:46:07 INFO - 'LANGUAGE': 'en_US:en', 19:46:07 INFO - 'LOGNAME': 'cltbld', 19:46:07 INFO - 'MAIL': '/var/mail/cltbld', 19:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:07 INFO - 'MOZ_NO_REMOTE': '1', 19:46:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:07 INFO - 'NO_EM_RESTART': '1', 19:46:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:07 INFO - 'PWD': '/builds/slave/test', 19:46:07 INFO - 'SHELL': '/bin/bash', 19:46:07 INFO - 'SHLVL': '1', 19:46:07 INFO - 'TERM': 'linux', 19:46:07 INFO - 'TMOUT': '86400', 19:46:07 INFO - 'USER': 'cltbld', 19:46:07 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:07 INFO - '_': '/tools/buildbot/bin/python'} 19:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:46:07 INFO - Cleaning up... 19:46:07 INFO - Return code: 0 19:46:07 INFO - Running post-action listener: _resource_record_post_action 19:46:07 INFO - Running post-action listener: _start_resource_monitoring 19:46:07 INFO - Starting resource monitoring. 19:46:07 INFO - [mozharness: 2017-05-12 02:46:07.366018Z] Finished create-virtualenv step (success) 19:46:07 INFO - [mozharness: 2017-05-12 02:46:07.366523Z] Running install step. 19:46:07 INFO - Running pre-action listener: _resource_record_pre_action 19:46:07 INFO - Running main action method: install 19:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:46:07 INFO - Reading from file tmpfile_stdout 19:46:07 INFO - Detecting whether we're running mozinstall >=1.0... 19:46:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:46:07 INFO - Reading from file tmpfile_stdout 19:46:07 INFO - Output received: 19:46:07 INFO - Usage: mozinstall [options] installer 19:46:07 INFO - Options: 19:46:07 INFO - -h, --help show this help message and exit 19:46:07 INFO - -d DEST, --destination=DEST 19:46:07 INFO - Directory to install application into. [default: 19:46:07 INFO - "/builds/slave/test"] 19:46:07 INFO - --app=APP Application being installed. [default: firefox] 19:46:07 INFO - mkdir: /builds/slave/test/build/application 19:46:07 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'] 19:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:46:23 INFO - Reading from file tmpfile_stdout 19:46:23 INFO - Output received: 19:46:23 INFO - /builds/slave/test/build/application/firefox/firefox 19:46:23 INFO - Running post-action listener: _resource_record_post_action 19:46:23 INFO - [mozharness: 2017-05-12 02:46:23.592414Z] Finished install step (success) 19:46:23 INFO - [mozharness: 2017-05-12 02:46:23.592661Z] Running run-tests step. 19:46:23 INFO - Running pre-action listener: _resource_record_pre_action 19:46:23 INFO - Running main action method: run_tests 19:46:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:46:23 INFO - Python 2.7.3 19:46:23 INFO - Return code: 0 19:46:23 INFO - grabbing minidump binary from tooltool 19:46:23 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')]} 19:46:23 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 0x15c5a30>, '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 0x15c67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c6ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:46:23 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 19:46:23 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 19:46:23 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 19:46:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:46:23 INFO - Return code: 0 19:46:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:46:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:46:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:46:23 INFO - ENV: RUST_BACKTRACE is now 1 19:46:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:46:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:46:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:46:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:46:23 INFO - Using env: {'DISPLAY': ':0', 19:46:23 INFO - 'HOME': '/home/cltbld', 19:46:23 INFO - 'LANG': 'en_US.UTF-8', 19:46:23 INFO - 'LANGUAGE': 'en_US:en', 19:46:23 INFO - 'LOGNAME': 'cltbld', 19:46:23 INFO - 'MAIL': '/var/mail/cltbld', 19:46:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:46:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:46:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:23 INFO - 'MOZ_NO_REMOTE': '1', 19:46:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:46:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:23 INFO - 'NO_EM_RESTART': '1', 19:46:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:23 INFO - 'PWD': '/builds/slave/test', 19:46:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:46:23 INFO - 'RUST_BACKTRACE': '1', 19:46:23 INFO - 'SHELL': '/bin/bash', 19:46:23 INFO - 'SHLVL': '1', 19:46:23 INFO - 'TERM': 'linux', 19:46:23 INFO - 'TMOUT': '86400', 19:46:23 INFO - 'USER': 'cltbld', 19:46:23 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311', 19:46:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:23 INFO - '_': '/tools/buildbot/bin/python'} 19:46:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:46:23 INFO - mozversion application_buildid: 20170512021404 19:46:23 INFO - mozversion application_changeset: ad45e59c7683a1354c8b73566f653d515f8d9a42 19:46:23 INFO - mozversion application_display_name: Nightly 19:46:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:46:23 INFO - mozversion application_name: Firefox 19:46:23 INFO - mozversion application_remotingname: firefox 19:46:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:46:23 INFO - mozversion application_vendor: Mozilla 19:46:23 INFO - mozversion application_version: 55.0a1 19:46:23 INFO - mozversion platform_buildid: 20170512021404 19:46:23 INFO - mozversion platform_changeset: ad45e59c7683a1354c8b73566f653d515f8d9a42 19:46:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:46:23 INFO - mozversion platform_version: 55.0a1 19:46:23 INFO - using testdate: 1494557183 19:46:23 INFO - actual date: 1494557183 19:46:23 INFO - starting webserver on 'localhost:50625' 19:46:23 INFO - SUITE-START | Running 2 tests 19:46:23 INFO - TEST-START | dromaeo_css 19:46:23 INFO - operating with platform_type : linux_ 19:46:23 INFO - Initialising browser for dromaeo_css test... 19:46:23 INFO - TEST-INFO | started process 25233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDTNG3/profile http://localhost:50625/getInfo.html) 19:46:28 INFO - PID 25233 | __metrics Screen width/height:1600/1200 19:46:28 INFO - PID 25233 | colorDepth:24 19:46:28 INFO - PID 25233 | Browser inner width/height: 1024/695 19:46:28 INFO - PID 25233 | __metrics 19:46:29 INFO - PID 25233 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:46:29 INFO - PID 25233 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:46:30 INFO - TEST-INFO | 25233: exit 0 19:46:30 INFO - Browser initialized. 19:46:30 INFO - Running cycle 1/1 for dromaeo_css test... 19:46:30 INFO - TEST-INFO | started process 25327 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDTNG3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:42 INFO - PID 25327 | RSS: Main: 148668416 19:46:42 INFO - PID 25327 | 19:49:48 INFO - PID 25327 | Cycle 1(1): loaded http://localhost:50625/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50625/tests/dromaeo/cssquery-ext.html) 19:49:50 INFO - PID 25327 | RSS: Main: 251084800 19:49:50 INFO - PID 25327 | 19:52:52 INFO - PID 25327 | Cycle 1(1): loaded http://localhost:50625/tests/dromaeo/cssquery-ext.html (next: http://localhost:50625/tests/dromaeo/cssquery-jquery.html) 19:52:53 INFO - PID 25327 | RSS: Main: 226496512 19:52:53 INFO - PID 25327 | 19:55:55 INFO - PID 25327 | Cycle 1(1): loaded http://localhost:50625/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50625/tests/dromaeo/cssquery-mootools.html) 19:55:55 INFO - PID 25327 | RSS: Main: 214077440 19:55:55 INFO - PID 25327 | 19:58:57 INFO - PID 25327 | Cycle 1(1): loaded http://localhost:50625/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50625/tests/dromaeo/cssquery-prototype.html) 19:58:58 INFO - PID 25327 | RSS: Main: 178036736 19:58:58 INFO - PID 25327 | 20:02:01 INFO - PID 25327 | Cycle 1(1): loaded http://localhost:50625/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50625/tests/dromaeo/cssquery-yui.html) 20:02:02 INFO - PID 25327 | RSS: Main: 180027392 20:02:02 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | Cycle 1(1): loaded http://localhost:50625/tests/dromaeo/cssquery-yui.html (next: http://localhost:50625/tests/dromaeo/cssquery-dojo.html) 20:05:04 INFO - PID 25327 | RSS: Main: 233054208 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | __start_tp_report 20:05:04 INFO - PID 25327 | _x_x_mozilla_page_load 20:05:04 INFO - PID 25327 | _x_x_mozilla_page_load_details 20:05:04 INFO - PID 25327 | |i|pagename|runs| 20:05:04 INFO - PID 25327 | |0;dojo.html;2675.9866200669;2695.285749276441;2724.455108978205;2730.7542321191077;2736.6168736376903;4943.555080042795;5113.872153196167;5121.923171152431;5126.333576635032;5196.948030519694;5098.286239926404;5099.15863882459;5102.7448627568565;5148.304978827857;5159.226116082589;4218.641415479688;4453.576910193527;4532.546745325474;4584.587387135157;4609.423822022247;4548.295014272793;4585.5185205553435;4620.468646105691;4632.791524381394;4634.6987445816085;4441.489228738704;4484.551544845522;4505.36924830524;4510.684252102355;4511.052678937427;4519.728816271035;4537.410136682243;4550.817967281305;4553.134904368159;4563.794629241692;4526.502084770673;4543.909121817578;4579.67942244043;4586.839460618862;4593.862184134481;5105.412877519096;5108.310378098946;5118.667286626357;5121.436641969381;5122.257272695439;5353.652012619206;5417.918731219035;5418.133098704227;5506.504414602665;5566.86082847928;5456.890862182734;5525.640833345819;5622.690752008638;5629.071203251458;5631.662100273997;5142.51146141116;5178.844634660966;5181.35233095863;5186.325777648919;5186.896262074737;5590.636608620508;5636.689982051026;5641.830745077655;5643.097104463266;5654.971725141348;5300.31095957523;5366.114591092504;5366.463353664602;5368.382635996911;5455.018096742624;5547.667139971614;5549.7780088796;5558.221848941152;5558.527525160326;5600.467955544217;5352.19717042439;5423.105187643994;5437.021336159451;5515.034868897927;5537.197106419547;5480.6437581557075;5497.422770609029;5497.945020549823;5506.229127922094;5526.253955716007;4267.359896015622;4271.252530807096;4276.87169384919;4313.9137217255475;4317.568243175658;3652.0322114639644;3695.168587067942;3708.49935258742;3717.9256414872093;3768.0391500167284;4729.905401891943;4733.455652599995;4746.288056791509;4771.785269662873;4784.0192760484015;5564.86087847799;5568.888622227532;5573.303337082864;5573.554115670737;5581.581381396411;4625.606823420047;4650.767461626905;4687.39063921688;4733.124371991124;4742.1227072991605;4914.164592019437;4919.040787046563;4939.357883475197;4952.479989601112;4956.677815941952;5113.616478764033;5125.487451254994;5128.230765385222;5132.30713853625;5132.640715149903;5402.540784033401;5483.095289277384;5483.177523371526;5528.7235638218735;5538.196961440649;5132.178851383761;5183.740812959412;5221.373435187802;5222.817201397933;5223.033738758342;5429.6199266051;5437.8368648940595;5439.374471935778;5441.102218134122;5447.1829225616475;5319.867003324948;5454.509094181384;5456.59075569326;5507.146392309199;5609.1305847593685;5349.545288650353;5378.408375078817;5396.163594642837;5398.676079435247;5399.946000539944;5332.386775520709;5340.065488539568;5351.785928562814;5384.380796208487;5397.163439666858;5111.897762044812;5214.21786731978;5218.321618189611;5268.841934741964;5388.595855310789;5439.211314359317;5440.619156658996;5450.127979523258;5455.208994695826;5474.178873169057;5263.05265052279;5277.525022747971;5284.81503147388;5285.841424757264;5341.3857406398765 20:05:04 INFO - PID 25327 | |1;ext.html;704.623026680726;740.6629983357572;758.9848203035939;975.6877799104285;990.9454979976099;25660.23019309419;31232;32250.838745806297;32295;32384.67615323846;34575.82712086442;34683.653163468356;34797;34983.1254218644;35072.64927350726;5199.194124910645;6768.93231067688;6921.100256966587;6941.965290173542;6949.131358580177;5325.920111198335;6742.4268937140405;6931.029655848185;6934.72261109555;6944.02783610295;6914.792556223296;7047.647617619098;7048.5418447800985;7051.1186101737285;7053.506254562183;5217.73911304437;6934.72261109555;6991.335823096798;6998.230194678581;6998.825029374281;5210.635255532115;6635.701393437307;6823.044773731682;6826.761063362758;6829.180498340231;31586.842065789755;31946.840265798754;32003.51994720081;32058.519122213187;32068.358632827447;3042.995811382261;3083.4141513112663;3086.876524939;3088.90733278002;3089.119600913729;23311.067557297687;23326.41683957898;23356.532869342518;23370.298891033126;23386.18148364816;3089.165925200206;3123.3128711683394;3125.7811953163286;3137.278425962019;3137.3882092991876;141308;141655.99999999898;141801;142053;142332.99999999895;39553.40669889954;39610.60389396127;39632.801835990635;39641.60358396379;39655.80172099121;42264.78867605642;42275;42276.15447691029;42320.365194522106;42333.36499952503;11365.659030229106;11475.368854713071;11488.253263537843;11490.368029758276;11616.651500455;7754.108277548042;7834.099078606033;9074;9082.137196966278;9130.406523575946;11237.471568896934;11451.427428628534;11570.074394048455;11573.594924177612;11701.2979221247;5026.396832380138;5114.181730923035;5144.9742751286;5162.896742065138;5175.353080864892;865.7229686500385;903.9457632541937;908.4867050116663;912.9224015958717;930.464982634974;19795.406137815888;20913.686294705592;20994.580108397746;21260.149594016377;21323.46691332729;970.2044323654535;972.8783902012249;981.7692842182081;983.7934033852828;990.5096976996289;10787.190960677981;11090.112790976702;11201.66395008151;11228.606998755004;11405.885941140648;159620;159691;160274;160508;160989;39238.21523569513;39728.2054358917;39744.20511589752;39756.40365394521;39849.40225896614;1182.0319267417399;1229.8524177098627;1235.234154823997;1235.3637876493792;1244.5146392906775;19397.709034364212;19967.800321996594;20378.796212038284;20619.58760824745;20962.371128866158;29516;29601.555976659936;29613.5557966635;29616;29660;146243;146545.26727366296;146930;147092;147129;24553;24940.37649058788;28652.57021144643;28679.139625811258;28689.13932582026;32700.50949235716;34356.312873742885;34409.48385774165;34540.30919381649;34760.30479390347;25002.37494062663;25954.740452595233;26334;26573.867130664225;26739.598906016035;25863.482730345662;26317.73682263153;26562;26563.734362656127;27213.591796122677;28614.14157575276;28985.855070724512;29150;29161.270968225963;29345.266368340963;24707;24998.500029998933;25294.1147059852;25761.613575796004;25862.482750345265;20609.69085463749;20785.48036299044;20837.479063023547;20898.268560600307;21036.789632104097 20:05:04 INFO - PID 25327 | |2;jquery.html;422.0159673922969;427.4571294456041;430.69851104227047;442.230518897119;448.7128237927725;16526.091064991422;17161.485155445327;17213.397531086415;17716.557086072855;17846.91076544617;17626.911865440656;17977.37079202228;18808.247670093213;18933.053347332647;19057.52356191096;5168.9224661630105;5221.947780522184;5299.947000529984;5315.893682126355;5353.946460535403;5253.422123566424;5346.117890548055;5389.595780316472;5390.865228369303;5392.272043274166;5153.278541004243;5243.528082472577;5276.947230527703;5282.181261904411;5362;5224.320838291017;5279.947200527984;5287.048331300364;5292.603054770869;5294.073537131006;4632.559906808848;4767.42790865095;4787.16224660683;4804.5195480451675;4805.663603547753;19293.903530482257;19745.21019159232;19906.402807915787;20034.298799541946;20102.899485502625;1642.228152768209;1681.865450763936;1683.5370273174854;1684.6546457976085;1684.8230935751815;13793;14339.942213808949;14386.568402947929;14491.710165796625;14719.116852988855;2078.449210959097;2080.958380832375;2128.999370295958;2138.3050508584615;2140.77521860204;71798;76937;93375;93861;94978.52510737402;10314.587416503406;10424.39390152462;10541.472926353652;10648.414337211529;10859.131269498579;15390.075885581704;15470.226488675746;15520.92239538795;15525.534233973;15666.295016724274;6863;6948.478864085113;7131.964340178266;7134.857302854018;7209.279072092853;6203.348648391854;6222.782202622887;6289.685515724287;6366.076918846741;6378.52161087911;6830.419414349736;6980.441564674814;7002.509824312351;7100.538464999759;7101;1966.9901650491656;2012.5572374077678;2045.8670186437835;2069.617120832651;2229.9108035678714;847.6990668312784;870.7692461497708;883.7304622090325;890.1009979920327;898.0525545549508;17405.390811321544;17409.129543522773;17670.734938975667;18037.429389112698;18211.72682409765;882.5543100734088;894.18182363137;896.2605850173633;897;910.5629297937028;9341.205997490153;9557.470804671219;9804.852927206097;9876.901230987598;9912.752181195528;356131;358471;361848;373090;385520;75658;77978.4404311922;77982;78440.60779696066;78758.21241787623;1079.3308148947704;1084.078533246734;1108.7782443511169;1117.402189828454;1117.4189421500937;16846.57883552921;16935.121029425274;17319.653606928045;17739.37912173068;18425.447236582924;37549.06127346729;38637.2272554553;38811.6118838808;39917;40050;45737.31394029159;48885.75557122192;50186.498135018184;51134;53738.93915912644;7783.299503044753;8011.759647210593;8081;8110.391720620859;8353.24820766134;12771.808422873479;12999.805002924775;13006.414711337824;13148;13449.260290684106;11456.3698996556;11514.481848316678;11645.708857278636;11771.352575608424;11977.880221197676;10664.360138391721;10672.092872105957;10779.622713205;10963.443612677705;11082.722931926444;11358.318500889973;11470;11568.363739994382;11809.23239989398;11839;8027.5584842834205;8032.196780321921;8050.637721302673;8055.436119471581;8303.543305118277;10534.36793792375;10556.84164737545;10814;10814.945925270324;10861.40262285582 20:05:04 INFO - PID 25327 | |3;mootools.html;1228.348975043227;1281.8718128187184;1294.546908581997;1295.268173481982;1300.6618279247393;9913.801723965516;10311.896881031187;10327.535260913259;10327.535260913268;10335.896641033587;9604.519774011307;10152.035556622128;10201.642942497014;10242.334248273857;10293.279470437054;2112.41908475169;2151.9354419367446;2155.3210738617363;2160.8487405881597;2162.318869556085;2136.423165745248;2253.549290141978;2255.436140964759;2256.5148493073984;2259.164109279569;1871.1860340751816;1941.8640695151346;1945.6303302372576;1948.9610207795836;1949.3469687654654;2113.6723807809735;2242.9551408971893;2252.662100684894;2252.8197744180425;2257.5033492631596;2215.091812356926;2244.2706120510734;2249.100359856054;2255.8082562982167;2257.1535674122206;9944.154746846527;10233.334833235816;10237.79524409515;10238.283320167591;10241.795164096677;5600.747966341524;5780.0751879699465;5781.884362312772;5788.073908174714;5794.043982742843;3242.7405807535342;3447.258839349552;3509.227969846655;3524.823758812049;3559.1458050068054;1131.3607811586428;1132.6551143697097;1145.8052131137727;1168.4508281107865;1178.3401295274589;15068.849311506963;15275;15276.38894444232;15282.006669566554;15290.694186116216;4643.071385722869;4764.237721964539;4785.114753770564;4865.95134048655;4877.3903262092235;10931;10958.397288149068;10959.397233152094;10967;10968.287061340987;5573.526250268692;5630.85922851932;5633.971830140823;5642.153676948491;5645.2096706461125;5079.415867175248;5218.869528261748;5233.110371236899;5294.41761406245;5310.415854256029;3781.0925377909102;3914.941275880864;3952.4268980997595;3976.9602303977167;4054.878353649395;1077.6659235636978;1087.1085709717956;1097.1442275025493;1098.566066403766;1119.9832002519968;677.3057615943698;700.1353328639127;700.737223541172;709.1808960650457;718.1956209045902;5221.921671174935;5356.223347614574;5395.892082158413;5438.83683489496;5480.588955828329;501.83073438887305;512.6283444502781;515.2528833191886;519.2133917115573;524.7114087251996;3329.184349834306;3345.882894098695;3366.360391525602;3392.975875038461;3484.337975784593;12970.546030888874;12972.675683107998;13522.661933451742;13552.45790168402;13557.186568805706;12810.103292769416;13319.334033298297;13363.53227637028;13459.528916487876;13480.662983425493;7314.122305323395;7324.37742791858;7448;7450;7460.776176714708;6032.215811944419;6033.879322413615;6131.394157482962;6206.317305096526;6281.748730050929;11734.647960561197;11938.164328496921;11972.521099155936;12047.51809927593;12191.573294934635;11741;12136.393180341125;12219.633410997685;12305.077119215915;12338.876611234133;8290.668373265002;8373.413861029669;8511.404201705822;8521.318294536546;8525.658973640984;9515.857262141211;9671.661491847752;9758.951205243928;9764.511774411108;9767.21862251004;7967.411905966793;8069.354451643737;8240.711575094843;8251.463654862415;8361.414700970874;7869.724559640385;7962.522248665098;8127.552984585905;8218.137095605114;8329.83340333202;7208.675609597685;7392.44556658242;7392.445566582635;7408.29621186008;7413.332800048021;5302.814401495929;5382.73086345689;5393.730313484389;5399.136138217867;5450.1007333789485;5556.833295001156;5596.6921819298705;5599.284347091739;5630.23991761107;5677.205191273142 20:05:04 INFO - PID 25327 | |4;prototype.html;380.7696343712054;384.8826108037048;389.1147639121;394.4438341937868;397.253164051583;5358.678479291245;5375.48932851379;5414.377346605142;5419.972900135499;5432.782688692448;5191.766370513326;5280.3663560372715;5315.893682126355;5322.520973112419;5323.60072994525;3884.3784994400903;3996.8800935971967;4224.366345048236;4227.788610569467;4233.83064677414;3974.324364857981;4000.599940006005;4005.799710014502;4008.3586626139822;4012.277789997799;3962.9405558916637;3975.4831871856763;3978.5026871641044;3979.303621866162;3988.222296652154;3974.5627980922077;3978.4430179774854;3980.900477488057;3982.4822773039464;3983.9999999999854;3404.472306792451;3481.4081606126765;3488.9825550872083;3501.072215862798;3510.7717998330027;5039.3196918415915;5079;5081.974590127025;5082.6188035897085;5096.694198348074;4663.207254766698;4682;4682.953170468286;4689.522800317889;4689.60138388234;5269.525742683139;5477.780888764445;5493.478119578634;5510.91733623996;5535.557155427597;3918.8047645468123;3927.9410808837893;3929.5088113985753;3930.724849260553;3932.311845427039;5596.944030559683;5740.741666625012;5751.798687045975;5879.178533952922;5961.046232602763;3999.580044095328;4005.178938317638;4012.1975604878558;4013.9397909031386;4021.4571032910185;4041.5652443382764;4058.964963934178;4064.4309796628495;4064.5935406459116;4081.7142800003717;4369.3227549729845;4401.6698747594055;4649.3026046093355;4682.555157260055;4684.882877928079;5532.640378375393;5552.3892371839065;5559.471850174308;5562.694051827189;5607;1390.2214759734582;1404.2767974493145;1404.3469786549304;1408.3099281352063;1408.507022542102;913.5888850017519;932.5430539035824;934.2292608597934;940.8400571902793;954.6324665003884;4876.195427754381;4876.4392094909535;4881.316615673809;4887.340209071801;4906.215005599159;5718.199452076713;5735.3977832327;5747.281589801275;5754.424557544296;5768.817392434541;4709.411323584552;4829.9275510867365;4854.417469903564;4867.537583929521;4887.560119589254;2994.7304742573274;3015.1256135720832;3015.381846721417;3018.803777754439;3027.9394412111633;5297.523222909879;5309.973450132725;5322.520973112439;5322.840314790563;5334.759935802898;5683.573731970118;5826.621269617546;5849.766009359578;5859.970700146472;5874.765009399662;5318.414974352818;5327.520523152934;5336.119540275811;5342.919856202082;5364.30264065669;5526.392096869422;5715.5713321500225;5725.799597014085;5760.683162426107;5762.798302059407;5669.773209071591;5681.346645135863;5685;5688.573356998324;5700.287464066992;5151.2273159026445;5292.232626269247;5292.259083728358;5297.788088476418;5431.918521222264;3733.3280009598534;3876.7480113792512;3940.9211815764097;3978.9602103979746;3982.084120652208;5709.857253568694;5793.131030345482;5793.710314484343;5793.768249269982;5798.623089499169;5559.11054231321;5563.833085007456;5572.60991730575;5580.107182850725;5599.496045355774;5558.77648940427;5560.638558493685;5565.076620982279;5590.496855283044;5590.748416321357;5386.892262154814;5468.3437987440175;5482.259894914136;5484.149956756709;5514.5864060194845;5575.1915972184615;5598.860028499157;5613.249507741259;5618.129189975561;5623.128415095667;5443.047466693233;5451.4276001019325;5452.672839629635;5454.809081682122;5470.808521701881 20:05:04 INFO - PID 25327 | |5;yui.html;733.3912852332564;749.3555542233674;750.5271678842329;752.8230865746549;770.1605250277197;12221.511139554406;12748.362581870915;12856.807147892789;13520.661983450407;13536.593902182938;13790.58628241152;13863.861361386384;13872.30638468075;13933;14509.49216777413;823.7528741377578;846.86026805577;848.5036253791509;851.5954921412341;852.228732996639;616.408248081842;909.1181553892746;911.8632205169212;915.9816803663923;916.445550441983;691.0912150522073;899.3165194452198;922.2391526990226;924.796544760155;926.8841394825647;924.6809850601499;926.666400095965;927.2628260532892;928.3919033033374;931.5388882503171;684.9589024658536;684.9760258390983;685.8114018644862;686.0669489494284;687.5393486364148;14251.144931304156;14519.274036298248;14660;14689.853101468956;14890.553283401516;14207.644808879757;14392.92803535986;14715.117092974457;14760;14885.776713349309;11861;12070.27578345302;12194.81707774384;12214.26714397139;12225.144239903211;2869.153599688097;2912.606798082274;2921.0652591170833;2924.5759364891974;2927.6925922778014;67331.326686733;68036.6598167011;68554.65722671355;68726.3127368725;68931.65534172297;10041.096301332915;10211.846822297672;10247.897521024843;10342.948285258675;10452.529636166391;17365.30538778435;17395.826041739674;17395.91302043482;17419.564510887074;17433.041182735073;6172.629642221391;6422.6467544285515;6446.645434501053;6470.191226096738;6476.190476190476;3004.3841012592366;3079.0454958962346;3083.39873724626;3084.8611812468494;3085.8457077146054;3475.5481787367985;3504.8948531544092;3531.9823400883347;3558.697510711567;3561.821908904544;722.609790713009;727.9199288078307;728.4318231779221;729.824842037907;736.5948728199469;586.8444862111544;614.1126072824725;624.7563450254404;625.6652690810395;627.3005598757353;9610.855837162448;9777.853332200022;10346.22403319754;10368.222383321281;10652.087475149105;601.7262145723686;605.1346574398652;607.9057746049369;628.4689437425368;628.71707731521;4434.268345722984;4505.43682039745;4506.729596224237;4531.13908357411;4619.769011549409;55061.449385506436;56135.43864561302;57852;60328;60743.39256607466;48346.758266208446;49899.75050124799;50292.49707502878;50436.74781626142;51029.48970510322;44641.55358446439;46288;46324;46761.29858052132;46828;9773.07155820196;10486.318389304672;10623.840642390216;10721.088707459954;10865.674029779118;19644.50888727793;25010;25567.360815979748;26009.739902600733;26100.216993490227;42437.78781106198;43037.78481107575;43185;43684.56315436932;44430.55569444394;13344.799828002393;13477.326133693472;13749.862501375259;13929.651758706113;13952;15384.461543845911;15498.225088745292;15571.221438927781;15578.766318505004;15805.209739513208;14072.94452916015;14126.223057731513;14338;14391.064580802633;14535.41858325655;13545.59363219105;13604;13753.587392378246;13885.305734713022;14084.366203520662;14032.649183770487;14151.504697335544;14257.073290236101;14417.85582144207;14576.125432473662;9951.751206219613;9998.400095994264;10117.571782771935;10214.591416343263;10395.076910771531;10865.13078953666;10967.860898340148;11339.943300283447;11475.8278625819;11785.764284714429 20:05:04 INFO - PID 25327 | __end_tp_report 20:05:04 INFO - PID 25327 | __start_cc_report 20:05:04 INFO - PID 25327 | _x_x_mozilla_cycle_collect,1244 20:05:04 INFO - PID 25327 | __end_cc_report 20:05:04 INFO - PID 25327 | __startTimestamp1494558304463__endTimestamp 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | ------- Summary: start ------- 20:05:04 INFO - PID 25327 | Number of tests: 6 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | [#0] dojo.html Cycles:165 Average:5025.87 Median:5196.95 stddev:611.80 (11.8%) stddev-sans-first:585.25 20:05:04 INFO - PID 25327 | Values: 2676.0 2695.3 2724.5 2730.8 2736.6 4943.6 5113.9 5121.9 5126.3 5196.9 5098.3 5099.2 5102.7 5148.3 5159.2 4218.6 4453.6 4532.5 4584.6 4609.4 4548.3 4585.5 4620.5 4632.8 4634.7 4441.5 4484.6 4505.4 4510.7 4511.1 4519.7 4537.4 4550.8 4553.1 4563.8 4526.5 4543.9 4579.7 4586.8 4593.9 5105.4 5108.3 5118.7 5121.4 5122.3 5353.7 5417.9 5418.1 5506.5 5566.9 5456.9 5525.6 5622.7 5629.1 5631.7 5142.5 5178.8 5181.4 5186.3 5186.9 5590.6 5636.7 5641.8 5643.1 5655.0 5300.3 5366.1 5366.5 5368.4 5455.0 5547.7 5549.8 5558.2 5558.5 5600.5 5352.2 5423.1 5437.0 5515.0 5537.2 5480.6 5497.4 5497.9 5506.2 5526.3 4267.4 4271.3 4276.9 4313.9 4317.6 3652.0 3695.2 3708.5 3717.9 3768.0 4729.9 4733.5 4746.3 4771.8 4784.0 5564.9 5568.9 5573.3 5573.6 5581.6 4625.6 4650.8 4687.4 4733.1 4742.1 4914.2 4919.0 4939.4 4952.5 4956.7 5113.6 5125.5 5128.2 5132.3 5132.6 5402.5 5483.1 5483.2 5528.7 5538.2 5132.2 5183.7 5221.4 5222.8 5223.0 5429.6 5437.8 5439.4 5441.1 5447.2 5319.9 5454.5 5456.6 5507.1 5609.1 5349.5 5378.4 5396.2 5398.7 5399.9 5332.4 5340.1 5351.8 5384.4 5397.2 5111.9 5214.2 5218.3 5268.8 5388.6 5439.2 5440.6 5450.1 5455.2 5474.2 5263.1 5277.5 5284.8 5285.8 5341.4 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | [#1] ext.html Cycles:180 Average:28953.46 Median:3089.14 stddev:38651.74 (1251.2%) stddev-sans-first:38701.96 20:05:04 INFO - PID 25327 | Values: 704.6 740.7 759.0 975.7 990.9 25660.2 31232.0 32250.8 32295.0 32384.7 34575.8 34683.7 34797.0 34983.1 35072.6 5199.2 6768.9 6921.1 6942.0 6949.1 5325.9 6742.4 6931.0 6934.7 6944.0 6914.8 7047.6 7048.5 7051.1 7053.5 5217.7 6934.7 6991.3 6998.2 6998.8 5210.6 6635.7 6823.0 6826.8 6829.2 31586.8 31946.8 32003.5 32058.5 32068.4 3043.0 3083.4 3086.9 3088.9 3089.1 23311.1 23326.4 23356.5 23370.3 23386.2 3089.2 3123.3 3125.8 3137.3 3137.4 141308.0 141656.0 141801.0 142053.0 142333.0 39553.4 39610.6 39632.8 39641.6 39655.8 42264.8 42275.0 42276.2 42320.4 42333.4 11365.7 11475.4 11488.3 11490.4 11616.7 7754.1 7834.1 9074.0 9082.1 9130.4 11237.5 11451.4 11570.1 11573.6 11701.3 5026.4 5114.2 5145.0 5162.9 5175.4 865.7 903.9 908.5 912.9 930.5 19795.4 20913.7 20994.6 21260.1 21323.5 970.2 972.9 981.8 983.8 990.5 10787.2 11090.1 11201.7 11228.6 11405.9 159620.0 159691.0 160274.0 160508.0 160989.0 39238.2 39728.2 39744.2 39756.4 39849.4 1182.0 1229.9 1235.2 1235.4 1244.5 19397.7 19967.8 20378.8 20619.6 20962.4 29516.0 29601.6 29613.6 29616.0 29660.0 146243.0 146545.3 146930.0 147092.0 147129.0 24553.0 24940.4 28652.6 28679.1 28689.1 32700.5 34356.3 34409.5 34540.3 34760.3 25002.4 25954.7 26334.0 26573.9 26739.6 25863.5 26317.7 26562.0 26563.7 27213.6 28614.1 28985.9 29150.0 29161.3 29345.3 24707.0 24998.5 25294.1 25761.6 25862.5 20609.7 20785.5 20837.5 20898.3 21036.8 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | [#2] jquery.html Cycles:180 Average:24730.87 Median:212078.61 stddev:61132.92 (28.8%) stddev-sans-first:61277.16 20:05:04 INFO - PID 25327 | Values: 422.0 427.5 430.7 442.2 448.7 16526.1 17161.5 17213.4 17716.6 17846.9 17626.9 17977.4 18808.2 18933.1 19057.5 5168.9 5221.9 5299.9 5315.9 5353.9 5253.4 5346.1 5389.6 5390.9 5392.3 5153.3 5243.5 5276.9 5282.2 5362.0 5224.3 5279.9 5287.0 5292.6 5294.1 4632.6 4767.4 4787.2 4804.5 4805.7 19293.9 19745.2 19906.4 20034.3 20102.9 1642.2 1681.9 1683.5 1684.7 1684.8 13793.0 14339.9 14386.6 14491.7 14719.1 2078.4 2081.0 2129.0 2138.3 2140.8 71798.0 76937.0 93375.0 93861.0 94978.5 10314.6 10424.4 10541.5 10648.4 10859.1 15390.1 15470.2 15520.9 15525.5 15666.3 6863.0 6948.5 7132.0 7134.9 7209.3 6203.3 6222.8 6289.7 6366.1 6378.5 6830.4 6980.4 7002.5 7100.5 7101.0 1967.0 2012.6 2045.9 2069.6 2229.9 847.7 870.8 883.7 890.1 898.1 17405.4 17409.1 17670.7 18037.4 18211.7 882.6 894.2 896.3 897.0 910.6 9341.2 9557.5 9804.9 9876.9 9912.8 356131.0 358471.0 361848.0 373090.0 385520.0 75658.0 77978.4 77982.0 78440.6 78758.2 1079.3 1084.1 1108.8 1117.4 1117.4 16846.6 16935.1 17319.7 17739.4 18425.4 37549.1 38637.2 38811.6 39917.0 40050.0 45737.3 48885.8 50186.5 51134.0 53738.9 7783.3 8011.8 8081.0 8110.4 8353.2 12771.8 12999.8 13006.4 13148.0 13449.3 11456.4 11514.5 11645.7 11771.4 11977.9 10664.4 10672.1 10779.6 10963.4 11082.7 11358.3 11470.0 11568.4 11809.2 11839.0 8027.6 8032.2 8050.6 8055.4 8303.5 10534.4 10556.8 10814.0 10814.9 10861.4 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | [#3] mootools.html Cycles:180 Average:6300.78 Median:3541.98 stddev:4076.33 (115.1%) stddev-sans-first:4069.95 20:05:04 INFO - PID 25327 | Values: 1228.3 1281.9 1294.5 1295.3 1300.7 9913.8 10311.9 10327.5 10327.5 10335.9 9604.5 10152.0 10201.6 10242.3 10293.3 2112.4 2151.9 2155.3 2160.8 2162.3 2136.4 2253.5 2255.4 2256.5 2259.2 1871.2 1941.9 1945.6 1949.0 1949.3 2113.7 2243.0 2252.7 2252.8 2257.5 2215.1 2244.3 2249.1 2255.8 2257.2 9944.2 10233.3 10237.8 10238.3 10241.8 5600.7 5780.1 5781.9 5788.1 5794.0 3242.7 3447.3 3509.2 3524.8 3559.1 1131.4 1132.7 1145.8 1168.5 1178.3 15068.8 15275.0 15276.4 15282.0 15290.7 4643.1 4764.2 4785.1 4866.0 4877.4 10931.0 10958.4 10959.4 10967.0 10968.3 5573.5 5630.9 5634.0 5642.2 5645.2 5079.4 5218.9 5233.1 5294.4 5310.4 3781.1 3914.9 3952.4 3977.0 4054.9 1077.7 1087.1 1097.1 1098.6 1120.0 677.3 700.1 700.7 709.2 718.2 5221.9 5356.2 5395.9 5438.8 5480.6 501.8 512.6 515.3 519.2 524.7 3329.2 3345.9 3366.4 3393.0 3484.3 12970.5 12972.7 13522.7 13552.5 13557.2 12810.1 13319.3 13363.5 13459.5 13480.7 7314.1 7324.4 7448.0 7450.0 7460.8 6032.2 6033.9 6131.4 6206.3 6281.7 11734.6 11938.2 11972.5 12047.5 12191.6 11741.0 12136.4 12219.6 12305.1 12338.9 8290.7 8373.4 8511.4 8521.3 8525.7 9515.9 9671.7 9759.0 9764.5 9767.2 7967.4 8069.4 8240.7 8251.5 8361.4 7869.7 7962.5 8127.6 8218.1 8329.8 7208.7 7392.4 7392.4 7408.3 7413.3 5302.8 5382.7 5393.7 5399.1 5450.1 5556.8 5596.7 5599.3 5630.2 5677.2 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | [#4] prototype.html Cycles:180 Average:4585.15 Median:5294.89 stddev:1350.05 (25.5%) stddev-sans-first:1316.44 20:05:04 INFO - PID 25327 | Values: 380.8 384.9 389.1 394.4 397.3 5358.7 5375.5 5414.4 5420.0 5432.8 5191.8 5280.4 5315.9 5322.5 5323.6 3884.4 3996.9 4224.4 4227.8 4233.8 3974.3 4000.6 4005.8 4008.4 4012.3 3962.9 3975.5 3978.5 3979.3 3988.2 3974.6 3978.4 3980.9 3982.5 3984.0 3404.5 3481.4 3489.0 3501.1 3510.8 5039.3 5079.0 5082.0 5082.6 5096.7 4663.2 4682.0 4683.0 4689.5 4689.6 5269.5 5477.8 5493.5 5510.9 5535.6 3918.8 3927.9 3929.5 3930.7 3932.3 5596.9 5740.7 5751.8 5879.2 5961.0 3999.6 4005.2 4012.2 4013.9 4021.5 4041.6 4059.0 4064.4 4064.6 4081.7 4369.3 4401.7 4649.3 4682.6 4684.9 5532.6 5552.4 5559.5 5562.7 5607.0 1390.2 1404.3 1404.3 1408.3 1408.5 913.6 932.5 934.2 940.8 954.6 4876.2 4876.4 4881.3 4887.3 4906.2 5718.2 5735.4 5747.3 5754.4 5768.8 4709.4 4829.9 4854.4 4867.5 4887.6 2994.7 3015.1 3015.4 3018.8 3027.9 5297.5 5310.0 5322.5 5322.8 5334.8 5683.6 5826.6 5849.8 5860.0 5874.8 5318.4 5327.5 5336.1 5342.9 5364.3 5526.4 5715.6 5725.8 5760.7 5762.8 5669.8 5681.3 5685.0 5688.6 5700.3 5151.2 5292.2 5292.3 5297.8 5431.9 3733.3 3876.7 3940.9 3979.0 3982.1 5709.9 5793.1 5793.7 5793.8 5798.6 5559.1 5563.8 5572.6 5580.1 5599.5 5558.8 5560.6 5565.1 5590.5 5590.7 5386.9 5468.3 5482.3 5484.1 5514.6 5575.2 5598.9 5613.2 5618.1 5623.1 5443.0 5451.4 5452.7 5454.8 5470.8 20:05:04 INFO - PID 25327 | 20:05:04 INFO - PID 25327 | [#5] yui.html Cycles:180 Average:14646.35 Median:3490.22 stddev:17002.33 (487.1%) stddev-sans-first:17017.93 20:05:04 INFO - PID 25327 | Values: 733.4 749.4 750.5 752.8 770.2 12221.5 12748.4 12856.8 13520.7 13536.6 13790.6 13863.9 13872.3 13933.0 14509.5 823.8 846.9 848.5 851.6 852.2 616.4 909.1 911.9 916.0 916.4 691.1 899.3 922.2 924.8 926.9 924.7 926.7 927.3 928.4 931.5 685.0 685.0 685.8 686.1 687.5 14251.1 14519.3 14660.0 14689.9 14890.6 14207.6 14392.9 14715.1 14760.0 14885.8 11861.0 12070.3 12194.8 12214.3 12225.1 2869.2 2912.6 2921.1 2924.6 2927.7 67331.3 68036.7 68554.7 68726.3 68931.7 10041.1 10211.8 10247.9 10342.9 10452.5 17365.3 17395.8 17395.9 17419.6 17433.0 6172.6 6422.6 6446.6 6470.2 6476.2 3004.4 3079.0 3083.4 3084.9 3085.8 3475.5 3504.9 3532.0 3558.7 3561.8 722.6 727.9 728.4 729.8 736.6 586.8 614.1 624.8 625.7 627.3 9610.9 9777.9 10346.2 10368.2 10652.1 601.7 605.1 607.9 628.5 628.7 4434.3 4505.4 4506.7 4531.1 4619.8 55061.4 56135.4 57852.0 60328.0 60743.4 48346.8 49899.8 50292.5 50436.7 51029.5 44641.6 46288.0 46324.0 46761.3 46828.0 9773.1 10486.3 10623.8 10721.1 10865.7 19644.5 25010.0 25567.4 26009.7 26100.2 42437.8 43037.8 43185.0 43684.6 44430.6 13344.8 13477.3 13749.9 13929.7 13952.0 15384.5 15498.2 15571.2 15578.8 15805.2 14072.9 14126.2 14338.0 14391.1 14535.4 13545.6 13604.0 13753.6 13885.3 14084.4 14032.6 14151.5 14257.1 14417.9 14576.1 9951.8 9998.4 10117.6 10214.6 10395.1 10865.1 10967.9 11339.9 11475.8 11785.8 20:05:04 INFO - PID 25327 | -------- Summary: end -------- 20:05:04 INFO - PID 25327 | 20:05:05 INFO - TEST-INFO | 25327: exit 0 20:05:05 INFO - TEST-OK | dromaeo_css | took 1121495ms 20:05:05 INFO - TEST-START | kraken 20:05:05 INFO - operating with platform_type : linux_ 20:05:05 INFO - Initialising browser for kraken test... 20:05:05 INFO - TEST-INFO | started process 25651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJGgKHx/profile http://localhost:50625/getInfo.html) 20:05:09 INFO - PID 25651 | __metrics Screen width/height:1600/1200 20:05:09 INFO - PID 25651 | colorDepth:24 20:05:09 INFO - PID 25651 | Browser inner width/height: 1024/695 20:05:09 INFO - PID 25651 | __metrics 20:05:10 INFO - PID 25651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:05:10 INFO - PID 25651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:05:11 INFO - TEST-INFO | 25651: exit 0 20:05:11 INFO - Browser initialized. 20:05:11 INFO - Running cycle 1/1 for kraken test... 20:05:11 INFO - TEST-INFO | started process 25749 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJGgKHx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:05:23 INFO - PID 25749 | RSS: Main: 148836352 20:05:23 INFO - PID 25749 | 20:05:26 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50625/tests/kraken/driver.html?testName=audio-beat-detection) 20:05:27 INFO - PID 25749 | RSS: Main: 221216768 20:05:27 INFO - PID 25749 | 20:05:30 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50625/tests/kraken/driver.html?testName=audio-dft) 20:05:30 INFO - PID 25749 | RSS: Main: 182661120 20:05:30 INFO - PID 25749 | 20:05:33 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50625/tests/kraken/driver.html?testName=audio-fft) 20:05:34 INFO - PID 25749 | RSS: Main: 264208384 20:05:34 INFO - PID 25749 | 20:05:36 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50625/tests/kraken/driver.html?testName=audio-oscillator) 20:05:37 INFO - PID 25749 | RSS: Main: 175665152 20:05:37 INFO - PID 25749 | 20:05:39 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50625/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:05:39 INFO - PID 25749 | RSS: Main: 177668096 20:05:39 INFO - PID 25749 | 20:05:42 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50625/tests/kraken/driver.html?testName=imaging-darkroom) 20:05:43 INFO - PID 25749 | RSS: Main: 238374912 20:05:43 INFO - PID 25749 | 20:05:47 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50625/tests/kraken/driver.html?testName=imaging-desaturate) 20:05:47 INFO - PID 25749 | RSS: Main: 237436928 20:05:47 INFO - PID 25749 | 20:05:50 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50625/tests/kraken/driver.html?testName=json-parse-financial) 20:05:51 INFO - PID 25749 | RSS: Main: 235257856 20:05:51 INFO - PID 25749 | 20:05:52 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50625/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:05:53 INFO - PID 25749 | RSS: Main: 200269824 20:05:53 INFO - PID 25749 | 20:05:55 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:05:55 INFO - PID 25749 | RSS: Main: 205049856 20:05:55 INFO - PID 25749 | 20:05:57 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:05:58 INFO - PID 25749 | RSS: Main: 214888448 20:05:58 INFO - PID 25749 | 20:06:01 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:06:01 INFO - PID 25749 | RSS: Main: 224468992 20:06:01 INFO - PID 25749 | 20:06:05 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:06:05 INFO - PID 25749 | RSS: Main: 219881472 20:06:05 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | Cycle 1(1): loaded http://localhost:50625/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50625/tests/kraken/driver.html?testName=ai-astar) 20:06:07 INFO - PID 25749 | RSS: Main: 269692928 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | __start_tp_report 20:06:07 INFO - PID 25749 | _x_x_mozilla_page_load 20:06:07 INFO - PID 25749 | _x_x_mozilla_page_load_details 20:06:07 INFO - PID 25749 | |i|pagename|runs| 20:06:07 INFO - PID 25749 | |0;ai-astar;108;112;109;111;111;111;110;110;111;110 20:06:07 INFO - PID 25749 | |1;audio-beat-detection;158;123;122;168;124;125;127;126;154;128 20:06:07 INFO - PID 25749 | |2;audio-dft;163;162;163;161;163;163;162;161;164;163 20:06:07 INFO - PID 25749 | |3;audio-fft;75;75;74;119;77;75;75;75;94;76 20:06:07 INFO - PID 25749 | |4;audio-oscillator;85;83;106;86;85;83;98;83;84;105 20:06:07 INFO - PID 25749 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;114;113;113 20:06:07 INFO - PID 25749 | |6;imaging-darkroom;149;148;150;150;149;150;150;150;149;149 20:06:07 INFO - PID 25749 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;94 20:06:07 INFO - PID 25749 | |8;json-parse-financial;53;53;53;53;54;55;55;55;54;54 20:06:07 INFO - PID 25749 | |9;json-stringify-tinderbox;56;55;55;56;55;56;56;55;56;56 20:06:07 INFO - PID 25749 | |10;stanford-crypto-aes;83;91;82;83;82;89;81;81;81;80 20:06:07 INFO - PID 25749 | |11;stanford-crypto-ccm;134;140;131;129;129;128;129;129;178;130 20:06:07 INFO - PID 25749 | |12;stanford-crypto-pbkdf2;190;193;192;188;193;189;188;182;190;187 20:06:07 INFO - PID 25749 | |13;stanford-crypto-sha256-iterative;73;76;72;78;74;72;76;73;72;70 20:06:07 INFO - PID 25749 | __end_tp_report 20:06:07 INFO - PID 25749 | __start_cc_report 20:06:07 INFO - PID 25749 | _x_x_mozilla_cycle_collect,1996 20:06:07 INFO - PID 25749 | __end_cc_report 20:06:07 INFO - PID 25749 | __startTimestamp1494558367718__endTimestamp 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | ------- Summary: start ------- 20:06:07 INFO - PID 25749 | Number of tests: 14 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:1.16 (1.0%) stddev-sans-first:0.88 20:06:07 INFO - PID 25749 | Values: 108.0 112.0 109.0 111.0 111.0 111.0 110.0 110.0 111.0 110.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:127.50 stddev:17.33 (13.6%) stddev-sans-first:16.36 20:06:07 INFO - PID 25749 | Values: 158.0 123.0 122.0 168.0 124.0 125.0 127.0 126.0 154.0 128.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 20:06:07 INFO - PID 25749 | Values: 163.0 162.0 163.0 161.0 163.0 163.0 162.0 161.0 164.0 163.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:14.45 (19.3%) stddev-sans-first:15.14 20:06:07 INFO - PID 25749 | Values: 75.0 75.0 74.0 119.0 77.0 75.0 75.0 75.0 94.0 76.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:84.50 stddev:9.39 (11.1%) stddev-sans-first:9.80 20:06:07 INFO - PID 25749 | Values: 85.0 83.0 106.0 86.0 85.0 83.0 98.0 83.0 84.0 105.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:06:07 INFO - PID 25749 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:150.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 20:06:07 INFO - PID 25749 | Values: 149.0 148.0 150.0 150.0 149.0 150.0 150.0 150.0 149.0 149.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 20:06:07 INFO - PID 25749 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.87 20:06:07 INFO - PID 25749 | Values: 53.0 53.0 53.0 53.0 54.0 55.0 55.0 55.0 54.0 54.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:56.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 20:06:07 INFO - PID 25749 | Values: 56.0 55.0 55.0 56.0 55.0 56.0 56.0 55.0 56.0 56.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:82.50 stddev:3.68 (4.5%) stddev-sans-first:3.91 20:06:07 INFO - PID 25749 | Values: 83.0 91.0 82.0 83.0 82.0 89.0 81.0 81.0 81.0 80.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:130.50 stddev:15.29 (11.7%) stddev-sans-first:16.21 20:06:07 INFO - PID 25749 | Values: 134.0 140.0 131.0 129.0 129.0 128.0 129.0 129.0 178.0 130.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:190.00 stddev:3.29 (1.7%) stddev-sans-first:3.48 20:06:07 INFO - PID 25749 | Values: 190.0 193.0 192.0 188.0 193.0 189.0 188.0 182.0 190.0 187.0 20:06:07 INFO - PID 25749 | 20:06:07 INFO - PID 25749 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:73.50 stddev:2.41 (3.3%) stddev-sans-first:2.55 20:06:07 INFO - PID 25749 | Values: 73.0 76.0 72.0 78.0 74.0 72.0 76.0 73.0 72.0 70.0 20:06:07 INFO - PID 25749 | -------- Summary: end -------- 20:06:07 INFO - PID 25749 | 20:06:08 INFO - TEST-INFO | 25749: exit 0 20:06:08 INFO - TEST-OK | kraken | took 63553ms 20:06:08 INFO - SUITE-END | took 1185s 20:06:09 INFO - Completed test suite (00:19:45) 20:06:09 INFO - javascript benchmark 20:06:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6556.154568502306, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2675.9866200669, 2695.285749276441, 2724.455108978205, 2730.7542321191077, 2736.6168736376903, 4943.555080042795, 5113.872153196167, 5121.923171152431, 5126.333576635032, 5196.948030519694, 5098.286239926404, 5099.15863882459, 5102.7448627568565, 5148.304978827857, 5159.226116082589, 4218.641415479688, 4453.576910193527, 4532.546745325474, 4584.587387135157, 4609.423822022247, 4548.295014272793, 4585.5185205553435, 4620.468646105691, 4632.791524381394, 4634.6987445816085, 4441.489228738704, 4484.551544845522, 4505.36924830524, 4510.684252102355, 4511.052678937427, 4519.728816271035, 4537.410136682243, 4550.817967281305, 4553.134904368159, 4563.794629241692, 4526.502084770673, 4543.909121817578, 4579.67942244043, 4586.839460618862, 4593.862184134481, 5105.412877519096, 5108.310378098946, 5118.667286626357, 5121.436641969381, 5122.257272695439, 5353.652012619206, 5417.918731219035, 5418.133098704227, 5506.504414602665, 5566.86082847928, 5456.890862182734, 5525.640833345819, 5622.690752008638, 5629.071203251458, 5631.662100273997, 5142.51146141116, 5178.844634660966, 5181.35233095863, 5186.325777648919, 5186.896262074737, 5590.636608620508, 5636.689982051026, 5641.830745077655, 5643.097104463266, 5654.971725141348, 5300.31095957523, 5366.114591092504, 5366.463353664602, 5368.382635996911, 5455.018096742624, 5547.667139971614, 5549.7780088796, 5558.221848941152, 5558.527525160326, 5600.467955544217, 5352.19717042439, 5423.105187643994, 5437.021336159451, 5515.034868897927, 5537.197106419547, 5480.6437581557075, 5497.422770609029, 5497.945020549823, 5506.229127922094, 5526.253955716007, 4267.359896015622, 4271.252530807096, 4276.87169384919, 4313.9137217255475, 4317.568243175658, 3652.0322114639644, 3695.168587067942, 3708.49935258742, 3717.9256414872093, 3768.0391500167284, 4729.905401891943, 4733.455652599995, 4746.288056791509, 4771.785269662873, 4784.0192760484015, 5564.86087847799, 5568.888622227532, 5573.303337082864, 5573.554115670737, 5581.581381396411, 4625.606823420047, 4650.767461626905, 4687.39063921688, 4733.124371991124, 4742.1227072991605, 4914.164592019437, 4919.040787046563, 4939.357883475197, 4952.479989601112, 4956.677815941952, 5113.616478764033, 5125.487451254994, 5128.230765385222, 5132.30713853625, 5132.640715149903, 5402.540784033401, 5483.095289277384, 5483.177523371526, 5528.7235638218735, 5538.196961440649, 5132.178851383761, 5183.740812959412, 5221.373435187802, 5222.817201397933, 5223.033738758342, 5429.6199266051, 5437.8368648940595, 5439.374471935778, 5441.102218134122, 5447.1829225616475, 5319.867003324948, 5454.509094181384, 5456.59075569326, 5507.146392309199, 5609.1305847593685, 5349.545288650353, 5378.408375078817, 5396.163594642837, 5398.676079435247, 5399.946000539944, 5332.386775520709, 5340.065488539568, 5351.785928562814, 5384.380796208487, 5397.163439666858, 5111.897762044812, 5214.21786731978, 5218.321618189611, 5268.841934741964, 5388.595855310789, 5439.211314359317, 5440.619156658996, 5450.127979523258, 5455.208994695826, 5474.178873169057, 5263.05265052279, 5277.525022747971, 5284.81503147388, 5285.841424757264, 5341.3857406398765], "value": 4980.5544653381185, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.623026680726, 740.6629983357572, 758.9848203035939, 975.6877799104285, 990.9454979976099, 25660.23019309419, 31232.0, 32250.838745806297, 32295.0, 32384.67615323846, 34575.82712086442, 34683.653163468356, 34797.0, 34983.1254218644, 35072.64927350726, 5199.194124910645, 6768.93231067688, 6921.100256966587, 6941.965290173542, 6949.131358580177, 5325.920111198335, 6742.4268937140405, 6931.029655848185, 6934.72261109555, 6944.02783610295, 6914.792556223296, 7047.647617619098, 7048.5418447800985, 7051.1186101737285, 7053.506254562183, 5217.73911304437, 6934.72261109555, 6991.335823096798, 6998.230194678581, 6998.825029374281, 5210.635255532115, 6635.701393437307, 6823.044773731682, 6826.761063362758, 6829.180498340231, 31586.842065789755, 31946.840265798754, 32003.51994720081, 32058.519122213187, 32068.358632827447, 3042.995811382261, 3083.4141513112663, 3086.876524939, 3088.90733278002, 3089.119600913729, 23311.067557297687, 23326.41683957898, 23356.532869342518, 23370.298891033126, 23386.18148364816, 3089.165925200206, 3123.3128711683394, 3125.7811953163286, 3137.278425962019, 3137.3882092991876, 141308.0, 141655.99999999898, 141801.0, 142053.0, 142332.99999999895, 39553.40669889954, 39610.60389396127, 39632.801835990635, 39641.60358396379, 39655.80172099121, 42264.78867605642, 42275.0, 42276.15447691029, 42320.365194522106, 42333.36499952503, 11365.659030229106, 11475.368854713071, 11488.253263537843, 11490.368029758276, 11616.651500455, 7754.108277548042, 7834.099078606033, 9074.0, 9082.137196966278, 9130.406523575946, 11237.471568896934, 11451.427428628534, 11570.074394048455, 11573.594924177612, 11701.2979221247, 5026.396832380138, 5114.181730923035, 5144.9742751286, 5162.896742065138, 5175.353080864892, 865.7229686500385, 903.9457632541937, 908.4867050116663, 912.9224015958717, 930.464982634974, 19795.406137815888, 20913.686294705592, 20994.580108397746, 21260.149594016377, 21323.46691332729, 970.2044323654535, 972.8783902012249, 981.7692842182081, 983.7934033852828, 990.5096976996289, 10787.190960677981, 11090.112790976702, 11201.66395008151, 11228.606998755004, 11405.885941140648, 159620.0, 159691.0, 160274.0, 160508.0, 160989.0, 39238.21523569513, 39728.2054358917, 39744.20511589752, 39756.40365394521, 39849.40225896614, 1182.0319267417399, 1229.8524177098627, 1235.234154823997, 1235.3637876493792, 1244.5146392906775, 19397.709034364212, 19967.800321996594, 20378.796212038284, 20619.58760824745, 20962.371128866158, 29516.0, 29601.555976659936, 29613.5557966635, 29616.0, 29660.0, 146243.0, 146545.26727366296, 146930.0, 147092.0, 147129.0, 24553.0, 24940.37649058788, 28652.57021144643, 28679.139625811258, 28689.13932582026, 32700.50949235716, 34356.312873742885, 34409.48385774165, 34540.30919381649, 34760.30479390347, 25002.37494062663, 25954.740452595233, 26334.0, 26573.867130664225, 26739.598906016035, 25863.482730345662, 26317.73682263153, 26562.0, 26563.734362656127, 27213.591796122677, 28614.14157575276, 28985.855070724512, 29150.0, 29161.270968225963, 29345.266368340963, 24707.0, 24998.500029998933, 25294.1147059852, 25761.613575796004, 25862.482750345265, 20609.69085463749, 20785.48036299044, 20837.479063023547, 20898.268560600307, 21036.789632104097], "value": 13948.948718564154, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.0159673922969, 427.4571294456041, 430.69851104227047, 442.230518897119, 448.7128237927725, 16526.091064991422, 17161.485155445327, 17213.397531086415, 17716.557086072855, 17846.91076544617, 17626.911865440656, 17977.37079202228, 18808.247670093213, 18933.053347332647, 19057.52356191096, 5168.9224661630105, 5221.947780522184, 5299.947000529984, 5315.893682126355, 5353.946460535403, 5253.422123566424, 5346.117890548055, 5389.595780316472, 5390.865228369303, 5392.272043274166, 5153.278541004243, 5243.528082472577, 5276.947230527703, 5282.181261904411, 5362.0, 5224.320838291017, 5279.947200527984, 5287.048331300364, 5292.603054770869, 5294.073537131006, 4632.559906808848, 4767.42790865095, 4787.16224660683, 4804.5195480451675, 4805.663603547753, 19293.903530482257, 19745.21019159232, 19906.402807915787, 20034.298799541946, 20102.899485502625, 1642.228152768209, 1681.865450763936, 1683.5370273174854, 1684.6546457976085, 1684.8230935751815, 13793.0, 14339.942213808949, 14386.568402947929, 14491.710165796625, 14719.116852988855, 2078.449210959097, 2080.958380832375, 2128.999370295958, 2138.3050508584615, 2140.77521860204, 71798.0, 76937.0, 93375.0, 93861.0, 94978.52510737402, 10314.587416503406, 10424.39390152462, 10541.472926353652, 10648.414337211529, 10859.131269498579, 15390.075885581704, 15470.226488675746, 15520.92239538795, 15525.534233973, 15666.295016724274, 6863.0, 6948.478864085113, 7131.964340178266, 7134.857302854018, 7209.279072092853, 6203.348648391854, 6222.782202622887, 6289.685515724287, 6366.076918846741, 6378.52161087911, 6830.419414349736, 6980.441564674814, 7002.509824312351, 7100.538464999759, 7101.0, 1966.9901650491656, 2012.5572374077678, 2045.8670186437835, 2069.617120832651, 2229.9108035678714, 847.6990668312784, 870.7692461497708, 883.7304622090325, 890.1009979920327, 898.0525545549508, 17405.390811321544, 17409.129543522773, 17670.734938975667, 18037.429389112698, 18211.72682409765, 882.5543100734088, 894.18182363137, 896.2605850173633, 897.0, 910.5629297937028, 9341.205997490153, 9557.470804671219, 9804.852927206097, 9876.901230987598, 9912.752181195528, 356131.0, 358471.0, 361848.0, 373090.0, 385520.0, 75658.0, 77978.4404311922, 77982.0, 78440.60779696066, 78758.21241787623, 1079.3308148947704, 1084.078533246734, 1108.7782443511169, 1117.402189828454, 1117.4189421500937, 16846.57883552921, 16935.121029425274, 17319.653606928045, 17739.37912173068, 18425.447236582924, 37549.06127346729, 38637.2272554553, 38811.6118838808, 39917.0, 40050.0, 45737.31394029159, 48885.75557122192, 50186.498135018184, 51134.0, 53738.93915912644, 7783.299503044753, 8011.759647210593, 8081.0, 8110.391720620859, 8353.24820766134, 12771.808422873479, 12999.805002924775, 13006.414711337824, 13148.0, 13449.260290684106, 11456.3698996556, 11514.481848316678, 11645.708857278636, 11771.352575608424, 11977.880221197676, 10664.360138391721, 10672.092872105957, 10779.622713205, 10963.443612677705, 11082.722931926444, 11358.318500889973, 11470.0, 11568.363739994382, 11809.23239989398, 11839.0, 8027.5584842834205, 8032.196780321921, 8050.637721302673, 8055.436119471581, 8303.543305118277, 10534.36793792375, 10556.84164737545, 10814.0, 10814.945925270324, 10861.40262285582], "value": 8779.773764576092, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.348975043227, 1281.8718128187184, 1294.546908581997, 1295.268173481982, 1300.6618279247393, 9913.801723965516, 10311.896881031187, 10327.535260913259, 10327.535260913268, 10335.896641033587, 9604.519774011307, 10152.035556622128, 10201.642942497014, 10242.334248273857, 10293.279470437054, 2112.41908475169, 2151.9354419367446, 2155.3210738617363, 2160.8487405881597, 2162.318869556085, 2136.423165745248, 2253.549290141978, 2255.436140964759, 2256.5148493073984, 2259.164109279569, 1871.1860340751816, 1941.8640695151346, 1945.6303302372576, 1948.9610207795836, 1949.3469687654654, 2113.6723807809735, 2242.9551408971893, 2252.662100684894, 2252.8197744180425, 2257.5033492631596, 2215.091812356926, 2244.2706120510734, 2249.100359856054, 2255.8082562982167, 2257.1535674122206, 9944.154746846527, 10233.334833235816, 10237.79524409515, 10238.283320167591, 10241.795164096677, 5600.747966341524, 5780.0751879699465, 5781.884362312772, 5788.073908174714, 5794.043982742843, 3242.7405807535342, 3447.258839349552, 3509.227969846655, 3524.823758812049, 3559.1458050068054, 1131.3607811586428, 1132.6551143697097, 1145.8052131137727, 1168.4508281107865, 1178.3401295274589, 15068.849311506963, 15275.0, 15276.38894444232, 15282.006669566554, 15290.694186116216, 4643.071385722869, 4764.237721964539, 4785.114753770564, 4865.95134048655, 4877.3903262092235, 10931.0, 10958.397288149068, 10959.397233152094, 10967.0, 10968.287061340987, 5573.526250268692, 5630.85922851932, 5633.971830140823, 5642.153676948491, 5645.2096706461125, 5079.415867175248, 5218.869528261748, 5233.110371236899, 5294.41761406245, 5310.415854256029, 3781.0925377909102, 3914.941275880864, 3952.4268980997595, 3976.9602303977167, 4054.878353649395, 1077.6659235636978, 1087.1085709717956, 1097.1442275025493, 1098.566066403766, 1119.9832002519968, 677.3057615943698, 700.1353328639127, 700.737223541172, 709.1808960650457, 718.1956209045902, 5221.921671174935, 5356.223347614574, 5395.892082158413, 5438.83683489496, 5480.588955828329, 501.83073438887305, 512.6283444502781, 515.2528833191886, 519.2133917115573, 524.7114087251996, 3329.184349834306, 3345.882894098695, 3366.360391525602, 3392.975875038461, 3484.337975784593, 12970.546030888874, 12972.675683107998, 13522.661933451742, 13552.45790168402, 13557.186568805706, 12810.103292769416, 13319.334033298297, 13363.53227637028, 13459.528916487876, 13480.662983425493, 7314.122305323395, 7324.37742791858, 7448.0, 7450.0, 7460.776176714708, 6032.215811944419, 6033.879322413615, 6131.394157482962, 6206.317305096526, 6281.748730050929, 11734.647960561197, 11938.164328496921, 11972.521099155936, 12047.51809927593, 12191.573294934635, 11741.0, 12136.393180341125, 12219.633410997685, 12305.077119215915, 12338.876611234133, 8290.668373265002, 8373.413861029669, 8511.404201705822, 8521.318294536546, 8525.658973640984, 9515.857262141211, 9671.661491847752, 9758.951205243928, 9764.511774411108, 9767.21862251004, 7967.411905966793, 8069.354451643737, 8240.711575094843, 8251.463654862415, 8361.414700970874, 7869.724559640385, 7962.522248665098, 8127.552984585905, 8218.137095605114, 8329.83340333202, 7208.675609597685, 7392.44556658242, 7392.445566582635, 7408.29621186008, 7413.332800048021, 5302.814401495929, 5382.73086345689, 5393.730313484389, 5399.136138217867, 5450.1007333789485, 5556.833295001156, 5596.6921819298705, 5599.284347091739, 5630.23991761107, 5677.205191273142], "value": 4680.044531142917, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.7696343712054, 384.8826108037048, 389.1147639121, 394.4438341937868, 397.253164051583, 5358.678479291245, 5375.48932851379, 5414.377346605142, 5419.972900135499, 5432.782688692448, 5191.766370513326, 5280.3663560372715, 5315.893682126355, 5322.520973112419, 5323.60072994525, 3884.3784994400903, 3996.8800935971967, 4224.366345048236, 4227.788610569467, 4233.83064677414, 3974.324364857981, 4000.599940006005, 4005.799710014502, 4008.3586626139822, 4012.277789997799, 3962.9405558916637, 3975.4831871856763, 3978.5026871641044, 3979.303621866162, 3988.222296652154, 3974.5627980922077, 3978.4430179774854, 3980.900477488057, 3982.4822773039464, 3983.9999999999854, 3404.472306792451, 3481.4081606126765, 3488.9825550872083, 3501.072215862798, 3510.7717998330027, 5039.3196918415915, 5079.0, 5081.974590127025, 5082.6188035897085, 5096.694198348074, 4663.207254766698, 4682.0, 4682.953170468286, 4689.522800317889, 4689.60138388234, 5269.525742683139, 5477.780888764445, 5493.478119578634, 5510.91733623996, 5535.557155427597, 3918.8047645468123, 3927.9410808837893, 3929.5088113985753, 3930.724849260553, 3932.311845427039, 5596.944030559683, 5740.741666625012, 5751.798687045975, 5879.178533952922, 5961.046232602763, 3999.580044095328, 4005.178938317638, 4012.1975604878558, 4013.9397909031386, 4021.4571032910185, 4041.5652443382764, 4058.964963934178, 4064.4309796628495, 4064.5935406459116, 4081.7142800003717, 4369.3227549729845, 4401.6698747594055, 4649.3026046093355, 4682.555157260055, 4684.882877928079, 5532.640378375393, 5552.3892371839065, 5559.471850174308, 5562.694051827189, 5607.0, 1390.2214759734582, 1404.2767974493145, 1404.3469786549304, 1408.3099281352063, 1408.507022542102, 913.5888850017519, 932.5430539035824, 934.2292608597934, 940.8400571902793, 954.6324665003884, 4876.195427754381, 4876.4392094909535, 4881.316615673809, 4887.340209071801, 4906.215005599159, 5718.199452076713, 5735.3977832327, 5747.281589801275, 5754.424557544296, 5768.817392434541, 4709.411323584552, 4829.9275510867365, 4854.417469903564, 4867.537583929521, 4887.560119589254, 2994.7304742573274, 3015.1256135720832, 3015.381846721417, 3018.803777754439, 3027.9394412111633, 5297.523222909879, 5309.973450132725, 5322.520973112439, 5322.840314790563, 5334.759935802898, 5683.573731970118, 5826.621269617546, 5849.766009359578, 5859.970700146472, 5874.765009399662, 5318.414974352818, 5327.520523152934, 5336.119540275811, 5342.919856202082, 5364.30264065669, 5526.392096869422, 5715.5713321500225, 5725.799597014085, 5760.683162426107, 5762.798302059407, 5669.773209071591, 5681.346645135863, 5685.0, 5688.573356998324, 5700.287464066992, 5151.2273159026445, 5292.232626269247, 5292.259083728358, 5297.788088476418, 5431.918521222264, 3733.3280009598534, 3876.7480113792512, 3940.9211815764097, 3978.9602103979746, 3982.084120652208, 5709.857253568694, 5793.131030345482, 5793.710314484343, 5793.768249269982, 5798.623089499169, 5559.11054231321, 5563.833085007456, 5572.60991730575, 5580.107182850725, 5599.496045355774, 5558.77648940427, 5560.638558493685, 5565.076620982279, 5590.496855283044, 5590.748416321357, 5386.892262154814, 5468.3437987440175, 5482.259894914136, 5484.149956756709, 5514.5864060194845, 5575.1915972184615, 5598.860028499157, 5613.249507741259, 5618.129189975561, 5623.128415095667, 5443.047466693233, 5451.4276001019325, 5452.672839629635, 5454.809081682122, 5470.808521701881], "value": 4173.87449776773, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.3912852332564, 749.3555542233674, 750.5271678842329, 752.8230865746549, 770.1605250277197, 12221.511139554406, 12748.362581870915, 12856.807147892789, 13520.661983450407, 13536.593902182938, 13790.58628241152, 13863.861361386384, 13872.30638468075, 13933.0, 14509.49216777413, 823.7528741377578, 846.86026805577, 848.5036253791509, 851.5954921412341, 852.228732996639, 616.408248081842, 909.1181553892746, 911.8632205169212, 915.9816803663923, 916.445550441983, 691.0912150522073, 899.3165194452198, 922.2391526990226, 924.796544760155, 926.8841394825647, 924.6809850601499, 926.666400095965, 927.2628260532892, 928.3919033033374, 931.5388882503171, 684.9589024658536, 684.9760258390983, 685.8114018644862, 686.0669489494284, 687.5393486364148, 14251.144931304156, 14519.274036298248, 14660.0, 14689.853101468956, 14890.553283401516, 14207.644808879757, 14392.92803535986, 14715.117092974457, 14760.0, 14885.776713349309, 11861.0, 12070.27578345302, 12194.81707774384, 12214.26714397139, 12225.144239903211, 2869.153599688097, 2912.606798082274, 2921.0652591170833, 2924.5759364891974, 2927.6925922778014, 67331.326686733, 68036.6598167011, 68554.65722671355, 68726.3127368725, 68931.65534172297, 10041.096301332915, 10211.846822297672, 10247.897521024843, 10342.948285258675, 10452.529636166391, 17365.30538778435, 17395.826041739674, 17395.91302043482, 17419.564510887074, 17433.041182735073, 6172.629642221391, 6422.6467544285515, 6446.645434501053, 6470.191226096738, 6476.190476190476, 3004.3841012592366, 3079.0454958962346, 3083.39873724626, 3084.8611812468494, 3085.8457077146054, 3475.5481787367985, 3504.8948531544092, 3531.9823400883347, 3558.697510711567, 3561.821908904544, 722.609790713009, 727.9199288078307, 728.4318231779221, 729.824842037907, 736.5948728199469, 586.8444862111544, 614.1126072824725, 624.7563450254404, 625.6652690810395, 627.3005598757353, 9610.855837162448, 9777.853332200022, 10346.22403319754, 10368.222383321281, 10652.087475149105, 601.7262145723686, 605.1346574398652, 607.9057746049369, 628.4689437425368, 628.71707731521, 4434.268345722984, 4505.43682039745, 4506.729596224237, 4531.13908357411, 4619.769011549409, 55061.449385506436, 56135.43864561302, 57852.0, 60328.0, 60743.39256607466, 48346.758266208446, 49899.75050124799, 50292.49707502878, 50436.74781626142, 51029.48970510322, 44641.55358446439, 46288.0, 46324.0, 46761.29858052132, 46828.0, 9773.07155820196, 10486.318389304672, 10623.840642390216, 10721.088707459954, 10865.674029779118, 19644.50888727793, 25010.0, 25567.360815979748, 26009.739902600733, 26100.216993490227, 42437.78781106198, 43037.78481107575, 43185.0, 43684.56315436932, 44430.55569444394, 13344.799828002393, 13477.326133693472, 13749.862501375259, 13929.651758706113, 13952.0, 15384.461543845911, 15498.225088745292, 15571.221438927781, 15578.766318505004, 15805.209739513208, 14072.94452916015, 14126.223057731513, 14338.0, 14391.064580802633, 14535.41858325655, 13545.59363219105, 13604.0, 13753.587392378246, 13885.305734713022, 14084.366203520662, 14032.649183770487, 14151.504697335544, 14257.073290236101, 14417.85582144207, 14576.125432473662, 9951.751206219613, 9998.400095994264, 10117.571782771935, 10214.591416343263, 10395.076910771531, 10865.13078953666, 10967.860898340148, 11339.943300283447, 11475.8278625819, 11785.764284714429], "value": 6664.550883958426, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 109.0, 111.0, 111.0, 111.0, 110.0, 110.0, 111.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 122.0, 168.0, 124.0, 125.0, 127.0, 126.0, 154.0, 128.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 161.0, 163.0, 163.0, 162.0, 161.0, 164.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 119.0, 77.0, 75.0, 75.0, 75.0, 94.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 106.0, 86.0, 85.0, 83.0, 98.0, 83.0, 84.0, 105.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 150.0, 149.0, 150.0, 150.0, 150.0, 149.0, 149.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 54.0, 55.0, 55.0, 55.0, 54.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 56.0, 55.0, 56.0, 56.0, 55.0, 56.0, 56.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 82.0, 83.0, 82.0, 89.0, 81.0, 81.0, 81.0, 80.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 140.0, 131.0, 129.0, 129.0, 128.0, 129.0, 129.0, 178.0, 130.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 193.0, 192.0, 188.0, 193.0, 189.0, 188.0, 182.0, 190.0, 187.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 72.0, 78.0, 74.0, 72.0, 76.0, 73.0, 72.0, 70.0], "value": 73.6, "unit": "score"}]}]} 20:06:09 INFO - Return code: 0 20:06:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:06:09 INFO - # TBPL SUCCESS # 20:06:09 INFO - Running post-action listener: _resource_record_post_action 20:06:09 INFO - [mozharness: 2017-05-12 03:06:09.554301Z] Finished run-tests step (success) 20:06:09 INFO - Running post-run listener: _resource_record_post_run 20:06:09 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 361402368; Read time: 8; Write time: 279784 20:06:09 INFO - TinderboxPrint: CPU usage
12.9% 20:06:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 20:06:09 INFO - TinderboxPrint: I/O write bytes / time
361,402,368 / 279,784 20:06:09 INFO - TinderboxPrint: CPU idle
8,353.7 (87.1%) 20:06:09 INFO - TinderboxPrint: CPU user
1,196.3 (12.5%) 20:06:09 INFO - TinderboxPrint: Swap in / out
0 / 0 20:06:09 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 102371328; Read time: 0; Write time: 118260 20:06:09 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 258482176; Read time: 0; Write time: 161496 20:06:10 INFO - Running post-run listener: _upload_blobber_files 20:06:10 INFO - Blob upload gear active. 20:06:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:06:10 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 20:06:10 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:06:10 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:06:10 INFO - (blobuploader) - INFO - Open directory for files ... 20:06:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:06:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:06:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:11 INFO - (blobuploader) - INFO - Done attempting. 20:06:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:06:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:06:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:12 INFO - (blobuploader) - INFO - Done attempting. 20:06:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:06:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:06:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:15 INFO - (blobuploader) - INFO - Done attempting. 20:06:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:06:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:06:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:16 INFO - (blobuploader) - INFO - Done attempting. 20:06:16 INFO - (blobuploader) - INFO - Iteration through files over. 20:06:16 INFO - Return code: 0 20:06:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:06:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:06:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfd713668ccfc30d2df460ee7ce878b7810a6a93ef194b3d95612e08a1b05d927f2d2adcc9eb3d7a2022fad79a2f2d0e5d1c3612bbfb78ab36ce4db3aaab0f5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33eb8ee783055d1eea5afbbc4b258612dd33806ae77f967b92a1536954aacb24f884583600dc9207b5e52846eaa07bd92a1a7138e21ff4376beb732242143a5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3658ea5f9ab89ae3baa86b300cc69612babe80f5da81cb0b59d0392765b9230af14c54a8ff8dddfab1b853fdee21a064cf99798bf638d5e571540ca4aff38473", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed95a124400e4f50f27ff338e2b2dad582c137a4e4a714225011db2ebf1693cae2f2950d61504707db7aad9ddce0eb2ef4257fe9dc4843864862140c4c702768"} 20:06:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:06:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:06:16 INFO - Contents: 20:06:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfd713668ccfc30d2df460ee7ce878b7810a6a93ef194b3d95612e08a1b05d927f2d2adcc9eb3d7a2022fad79a2f2d0e5d1c3612bbfb78ab36ce4db3aaab0f5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33eb8ee783055d1eea5afbbc4b258612dd33806ae77f967b92a1536954aacb24f884583600dc9207b5e52846eaa07bd92a1a7138e21ff4376beb732242143a5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3658ea5f9ab89ae3baa86b300cc69612babe80f5da81cb0b59d0392765b9230af14c54a8ff8dddfab1b853fdee21a064cf99798bf638d5e571540ca4aff38473", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed95a124400e4f50f27ff338e2b2dad582c137a4e4a714225011db2ebf1693cae2f2950d61504707db7aad9ddce0eb2ef4257fe9dc4843864862140c4c702768"} 20:06:16 INFO - Running post-run listener: copy_logs_to_upload_dir 20:06:16 INFO - Copying logs to upload dir... 20:06:16 INFO - mkdir: /builds/slave/test/build/upload/logs 20:06:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.045001 ========= master_lag: -0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-05-11 20:06:16.065783) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 20:06:16.069750) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfd713668ccfc30d2df460ee7ce878b7810a6a93ef194b3d95612e08a1b05d927f2d2adcc9eb3d7a2022fad79a2f2d0e5d1c3612bbfb78ab36ce4db3aaab0f5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33eb8ee783055d1eea5afbbc4b258612dd33806ae77f967b92a1536954aacb24f884583600dc9207b5e52846eaa07bd92a1a7138e21ff4376beb732242143a5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3658ea5f9ab89ae3baa86b300cc69612babe80f5da81cb0b59d0392765b9230af14c54a8ff8dddfab1b853fdee21a064cf99798bf638d5e571540ca4aff38473", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed95a124400e4f50f27ff338e2b2dad582c137a4e4a714225011db2ebf1693cae2f2950d61504707db7aad9ddce0eb2ef4257fe9dc4843864862140c4c702768"} build_url:https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011451 build_url: 'https://queue.taskcluster.net/v1/task/Y47ftADDTcOBFe5B9Lku6w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfd713668ccfc30d2df460ee7ce878b7810a6a93ef194b3d95612e08a1b05d927f2d2adcc9eb3d7a2022fad79a2f2d0e5d1c3612bbfb78ab36ce4db3aaab0f5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33eb8ee783055d1eea5afbbc4b258612dd33806ae77f967b92a1536954aacb24f884583600dc9207b5e52846eaa07bd92a1a7138e21ff4376beb732242143a5d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3658ea5f9ab89ae3baa86b300cc69612babe80f5da81cb0b59d0392765b9230af14c54a8ff8dddfab1b853fdee21a064cf99798bf638d5e571540ca4aff38473", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ed95a124400e4f50f27ff338e2b2dad582c137a4e4a714225011db2ebf1693cae2f2950d61504707db7aad9ddce0eb2ef4257fe9dc4843864862140c4c702768"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 20:06:16.094597) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:06:16.094915) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1494557119.159064-506216311 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:06:16.180163) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 20:06:16.183494) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 20:06:16.183785) ========= ========= Total master_lag: 0.03 =========