builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1494599418.07 results: success (0) revision: 1ec1d88637208098802e57d13db9033902e5a229 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.069877) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.070359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.070648) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.087211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.087743) ========= 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=bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-12 07:30:18-- 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% 219M=0s 2017-05-12 07:30:18 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214402 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.316451) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.316760) ========= 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=bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019758 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.366513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:18.366884) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1ec1d88637208098802e57d13db9033902e5a229 --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 1ec1d88637208098802e57d13db9033902e5a229 --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=bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-12 07:30:18,613 truncating revision to first 12 chars 2017-05-12 07:30:18,613 Setting DEBUG logging. 2017-05-12 07:30:18,613 attempt 1/10 2017-05-12 07:30:18,613 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1ec1d8863720?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 07:30:19,042 unpacking tar archive at: mozilla-central-1ec1d8863720/testing/mozharness/ program finished with exit code 0 elapsedTime=0.749353 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:19.130607) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:19.131028) ========= script_repo_revision: 1ec1d88637208098802e57d13db9033902e5a229 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:19.131564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:19.131833) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:30:19.142574) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-05-12 07:30:19.143258) ========= /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=bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:30:19 INFO - MultiFileLogger online at 20170512 07:30:19 in /builds/slave/test 07:30:19 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 07:30:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:30:19 INFO - {'append_to_log': False, 07:30:19 INFO - 'base_work_dir': '/builds/slave/test', 07:30:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:30:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:30:19 INFO - 'branch': 'Firefox-Non-PGO', 07:30:19 INFO - 'buildbot_json_path': 'buildprops.json', 07:30:19 INFO - 'config_files': ('talos/linux_config.py',), 07:30:19 INFO - 'default_actions': ('clobber', 07:30:19 INFO - 'read-buildbot-config', 07:30:19 INFO - 'download-and-extract', 07:30:19 INFO - 'populate-webroot', 07:30:19 INFO - 'create-virtualenv', 07:30:19 INFO - 'install', 07:30:19 INFO - 'run-tests'), 07:30:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:30:19 INFO - 'download_minidump_stackwalk': True, 07:30:19 INFO - 'download_symbols': 'ondemand', 07:30:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:30:19 INFO - 'tooltool.py': '/tools/tooltool.py', 07:30:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:30:19 INFO - '/tools/misc-python/virtualenv.py')}, 07:30:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:30:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:30:19 INFO - 'gecko_profile': False, 07:30:19 INFO - 'gecko_profile_interval': 0, 07:30:19 INFO - 'installer_path': 'installer.exe', 07:30:19 INFO - 'log_level': 'info', 07:30:19 INFO - 'log_name': 'talos', 07:30:19 INFO - 'log_to_console': True, 07:30:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:30:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:30:19 INFO - 'opt_config_files': (), 07:30:19 INFO - 'pip_index': False, 07:30:19 INFO - 'suite': 'dromaeojs', 07:30:19 INFO - 'system_bits': '32', 07:30:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:30:19 INFO - 'title': 'talos-linux64-ix-055', 07:30:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:30:19 INFO - 'use_talos_json': True, 07:30:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:30:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:30:19 INFO - 'work_dir': 'build'} 07:30:19 INFO - [mozharness: 2017-05-12 14:30:19.493003Z] Running clobber step. 07:30:19 INFO - Running pre-action listener: _resource_record_pre_action 07:30:19 INFO - Running main action method: clobber 07:30:19 INFO - rmtree: /builds/slave/test/build 07:30:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:30:20 INFO - Running post-action listener: _resource_record_post_action 07:30:20 INFO - [mozharness: 2017-05-12 14:30:20.444320Z] Finished clobber step (success) 07:30:20 INFO - [mozharness: 2017-05-12 14:30:20.444422Z] Running read-buildbot-config step. 07:30:20 INFO - Running pre-action listener: _resource_record_pre_action 07:30:20 INFO - Running main action method: read_buildbot_config 07:30:20 INFO - Using buildbot properties: 07:30:20 INFO - { 07:30:20 INFO - "project": "", 07:30:20 INFO - "product": "firefox", 07:30:20 INFO - "who": "cbook@mozilla.com", 07:30:20 INFO - "installer_path": "public/build/target.tar.bz2", 07:30:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:30:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:30:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:30:20 INFO - "stage_platform": "linux64", 07:30:20 INFO - "basedir": "/builds/slave/test", 07:30:20 INFO - "buildnumber": 1152, 07:30:20 INFO - "platform": "ubuntu64_hw", 07:30:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:30:20 INFO - "slavebuilddir": "test", 07:30:20 INFO - "taskId": "avAK-fc7SMaG8cm0TbHHyg", 07:30:20 INFO - "branch": "mozilla-central", 07:30:20 INFO - "script_repo_revision": "production", 07:30:20 INFO - "revision": "1ec1d88637208098802e57d13db9033902e5a229", 07:30:20 INFO - "slavename": "talos-linux64-ix-055", 07:30:20 INFO - "repo_path": "mozilla-central" 07:30:20 INFO - } 07:30:20 INFO - Finding installer, test and symbols from parent task. 07:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/avAK-fc7SMaG8cm0TbHHyg'}, attempt #1 07:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/avAK-fc7SMaG8cm0TbHHyg'}, attempt #1 07:30:21 INFO - Task dependencies: On88SKLTSaSVQZnFn_9u2Q 07:30:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q'}, attempt #1 07:30:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2 07:30:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/test_packages.json 07:30:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.crashreporter-symbols.zip 07:30:21 INFO - Running post-action listener: _resource_record_post_action 07:30:21 INFO - [mozharness: 2017-05-12 14:30:21.800020Z] Finished read-buildbot-config step (success) 07:30:21 INFO - [mozharness: 2017-05-12 14:30:21.800237Z] Running download-and-extract step. 07:30:21 INFO - Running pre-action listener: _resource_record_pre_action 07:30:21 INFO - Running main action method: download_and_extract 07:30:21 INFO - mkdir: /builds/slave/test/build/tests 07:30:21 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')]} 07:30:21 INFO - trying https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.test_packages.json 07:30:21 INFO - Downloading https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:30:22 INFO - Downloaded 986 bytes. 07:30:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:30:22 INFO - Using the following test package requirements: 07:30:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:30:22 INFO - u'common': [u'target.common.tests.zip'], 07:30:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:30:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:30:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:30:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:30:22 INFO - u'mozbase': [u'target.common.tests.zip'], 07:30:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:30:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:30:22 INFO - u'web-platform': [u'target.common.tests.zip', 07:30:22 INFO - u'target.web-platform.tests.zip'], 07:30:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:30:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.common.tests.zip 07:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.common.tests.zip into memory 07:30:23 INFO - Content-Length response header: 62447308 07:30:23 INFO - Bytes received: 62447308 07:30:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:30:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.common.tests.zip 07:30:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:30:34 INFO - Fetch https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.common.tests.zip into memory 07:30:35 INFO - Content-Length response header: 62447308 07:30:35 INFO - Bytes received: 62447308 07:30:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.talos.tests.zip 07:30:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:30:46 INFO - Fetch https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.talos.tests.zip into memory 07:30:47 INFO - Content-Length response header: 14409411 07:30:47 INFO - Bytes received: 14409411 07:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:47 INFO - trying https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2 07:30:47 INFO - Downloading https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:30:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:30:49 INFO - Downloaded 63487734 bytes. 07:30:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2 07:30:49 INFO - mkdir: /builds/slave/test/properties 07:30:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:30:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:30:49 INFO - Contents: 07:30:49 INFO - build_url:https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2 07:30:49 INFO - Running post-action listener: _resource_record_post_action 07:30:49 INFO - Running post-action listener: set_extra_try_arguments 07:30:49 INFO - [mozharness: 2017-05-12 14:30:49.052135Z] Finished download-and-extract step (success) 07:30:49 INFO - [mozharness: 2017-05-12 14:30:49.052234Z] Running populate-webroot step. 07:30:49 INFO - Running pre-action listener: _resource_record_pre_action 07:30:49 INFO - Running main action method: populate_webroot 07:30:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:30:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:30:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:30:49 INFO - '--tppagecycles', 07:30:49 INFO - '7'], 07:30:49 INFO - 'tests': ['tsvgm']}}, 07:30:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:30:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:30:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:30:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:30:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:30:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['damp', 'tps']}, 07:30:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'tests': ['damp', 'tps']}, 07:30:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['dromaeo_dom']}, 07:30:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:30:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:30:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:30:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['a11yr', 07:30:49 INFO - 'ts_paint', 07:30:49 INFO - 'tpaint', 07:30:49 INFO - 'sessionrestore', 07:30:49 INFO - 'sessionrestore_no_auto_restore', 07:30:49 INFO - 'tabpaint']}, 07:30:49 INFO - 'other-e10s': {'tests': ['a11yr', 07:30:49 INFO - 'ts_paint', 07:30:49 INFO - 'tpaint', 07:30:49 INFO - 'sessionrestore', 07:30:49 INFO - 'sessionrestore_no_auto_restore', 07:30:49 INFO - 'tabpaint']}, 07:30:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:30:49 INFO - 'ts_paint', 07:30:49 INFO - 'tpaint', 07:30:49 INFO - 'sessionrestore', 07:30:49 INFO - 'sessionrestore_no_auto_restore', 07:30:49 INFO - 'tabpaint']}, 07:30:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:30:49 INFO - 'ts_paint', 07:30:49 INFO - 'tpaint', 07:30:49 INFO - 'sessionrestore', 07:30:49 INFO - 'sessionrestore_no_auto_restore', 07:30:49 INFO - 'tabpaint']}, 07:30:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['a11yr', 07:30:49 INFO - 'ts_paint', 07:30:49 INFO - 'tpaint', 07:30:49 INFO - 'sessionrestore', 07:30:49 INFO - 'sessionrestore_no_auto_restore', 07:30:49 INFO - 'tabpaint']}, 07:30:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['a11yr', 07:30:49 INFO - 'ts_paint', 07:30:49 INFO - 'tpaint', 07:30:49 INFO - 'sessionrestore', 07:30:49 INFO - 'sessionrestore_no_auto_restore', 07:30:49 INFO - 'tabpaint']}, 07:30:49 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:30:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:30:49 INFO - 'bloom_basic_ref']}, 07:30:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['tsvgx', 07:30:49 INFO - 'tsvgr_opacity', 07:30:49 INFO - 'tart', 07:30:49 INFO - 'tscrollx', 07:30:49 INFO - 'cart', 07:30:49 INFO - 'tsvg_static']}, 07:30:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:30:49 INFO - 'tsvgr_opacity', 07:30:49 INFO - 'tart', 07:30:49 INFO - 'tscrollx', 07:30:49 INFO - 'cart', 07:30:49 INFO - 'tsvg_static']}, 07:30:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'talos_options': ['--disable-e10s'], 07:30:49 INFO - 'tests': ['tp5o']}, 07:30:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:30:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'talos_options': ['--disable-e10s', 07:30:49 INFO - '--xperf_path', 07:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:49 INFO - 'tests': ['tp5n']}, 07:30:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:30:49 INFO - 'talos_options': ['--xperf_path', 07:30:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:30:49 INFO - 'tests': ['tp5n']}}, 07:30:49 INFO - 'talos.zip': {'path': '', 07:30:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:30:49 INFO - Running post-action listener: _resource_record_post_action 07:30:49 INFO - [mozharness: 2017-05-12 14:30:49.065970Z] Finished populate-webroot step (success) 07:30:49 INFO - [mozharness: 2017-05-12 14:30:49.066060Z] Running create-virtualenv step. 07:30:49 INFO - Running pre-action listener: _resource_record_pre_action 07:30:49 INFO - Running main action method: create_virtualenv 07:30:49 INFO - Creating virtualenv /builds/slave/test/build/venv 07:30: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 07:30:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:30:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:30:49 INFO - Using real prefix '/usr' 07:30:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:30:49 INFO - Installing distribute.............................................................................................................................................................................................done. 07:30:51 INFO - Installing pip.................done. 07:30:51 INFO - Return code: 0 07:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:30:51 INFO - Reading from file tmpfile_stdout 07:30:51 INFO - Output received: 07:30:51 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) 07:30:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:51 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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:30:51 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 07:30:51 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 07:30:51 INFO - Using env: {'DISPLAY': ':0', 07:30:51 INFO - 'HOME': '/home/cltbld', 07:30:51 INFO - 'LANG': 'en_US.UTF-8', 07:30:51 INFO - 'LANGUAGE': 'en_US:en', 07:30:51 INFO - 'LOGNAME': 'cltbld', 07:30:51 INFO - 'MAIL': '/var/mail/cltbld', 07:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:51 INFO - 'MOZ_NO_REMOTE': '1', 07:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:51 INFO - 'NO_EM_RESTART': '1', 07:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:51 INFO - 'PWD': '/builds/slave/test', 07:30:51 INFO - 'SHELL': '/bin/bash', 07:30:51 INFO - 'SHLVL': '1', 07:30:51 INFO - 'TERM': 'linux', 07:30:51 INFO - 'TMOUT': '86400', 07:30:51 INFO - 'USER': 'cltbld', 07:30:51 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:51 INFO - '_': '/tools/buildbot/bin/python'} 07:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30: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 07:30:52 INFO - Cleaning up... 07:30:52 INFO - Return code: 0 07:30:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30: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')]} 07:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30: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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:30: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 07:30: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 07:30:52 INFO - Using env: {'DISPLAY': ':0', 07:30:52 INFO - 'HOME': '/home/cltbld', 07:30:52 INFO - 'LANG': 'en_US.UTF-8', 07:30:52 INFO - 'LANGUAGE': 'en_US:en', 07:30:52 INFO - 'LOGNAME': 'cltbld', 07:30:52 INFO - 'MAIL': '/var/mail/cltbld', 07:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:52 INFO - 'MOZ_NO_REMOTE': '1', 07:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:52 INFO - 'NO_EM_RESTART': '1', 07:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:52 INFO - 'PWD': '/builds/slave/test', 07:30:52 INFO - 'SHELL': '/bin/bash', 07:30:52 INFO - 'SHLVL': '1', 07:30:52 INFO - 'TERM': 'linux', 07:30:52 INFO - 'TMOUT': '86400', 07:30:52 INFO - 'USER': 'cltbld', 07:30:52 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:52 INFO - '_': '/tools/buildbot/bin/python'} 07:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:52 INFO - Downloading/unpacking psutil>=3.1.1 07:30:52 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 07:30:52 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 07:30:52 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 07:30:52 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 07:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:53 INFO - Installing collected packages: psutil 07:30:53 INFO - Running setup.py install for psutil 07:30:53 INFO - building 'psutil._psutil_linux' extension 07:30:53 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 07:30:53 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 07:30:53 INFO - building 'psutil._psutil_posix' extension 07:30:53 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 07:30:53 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 07:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:30:54 INFO - Successfully installed psutil 07:30:54 INFO - Cleaning up... 07:30:54 INFO - Return code: 0 07:30:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30: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')]} 07:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30: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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:30: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 07:30: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 07:30:54 INFO - Using env: {'DISPLAY': ':0', 07:30:54 INFO - 'HOME': '/home/cltbld', 07:30:54 INFO - 'LANG': 'en_US.UTF-8', 07:30:54 INFO - 'LANGUAGE': 'en_US:en', 07:30:54 INFO - 'LOGNAME': 'cltbld', 07:30:54 INFO - 'MAIL': '/var/mail/cltbld', 07:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:54 INFO - 'MOZ_NO_REMOTE': '1', 07:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:54 INFO - 'NO_EM_RESTART': '1', 07:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:54 INFO - 'PWD': '/builds/slave/test', 07:30:54 INFO - 'SHELL': '/bin/bash', 07:30:54 INFO - 'SHLVL': '1', 07:30:54 INFO - 'TERM': 'linux', 07:30:54 INFO - 'TMOUT': '86400', 07:30:54 INFO - 'USER': 'cltbld', 07:30:54 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:54 INFO - '_': '/tools/buildbot/bin/python'} 07:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:30:54 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 07:30:54 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 07:30:54 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 07:30:54 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 07:30:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:30:55 INFO - Installing collected packages: mozsystemmonitor 07:30:55 INFO - Running setup.py install for mozsystemmonitor 07:30:55 INFO - Successfully installed mozsystemmonitor 07:30:55 INFO - Cleaning up... 07:30:55 INFO - Return code: 0 07:30:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:30:55 INFO - Using env: {'DISPLAY': ':0', 07:30:55 INFO - 'HOME': '/home/cltbld', 07:30:55 INFO - 'LANG': 'en_US.UTF-8', 07:30:55 INFO - 'LANGUAGE': 'en_US:en', 07:30:55 INFO - 'LOGNAME': 'cltbld', 07:30:55 INFO - 'MAIL': '/var/mail/cltbld', 07:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:55 INFO - 'MOZ_NO_REMOTE': '1', 07:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:55 INFO - 'NO_EM_RESTART': '1', 07:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:55 INFO - 'PWD': '/builds/slave/test', 07:30:55 INFO - 'SHELL': '/bin/bash', 07:30:55 INFO - 'SHLVL': '1', 07:30:55 INFO - 'TERM': 'linux', 07:30:55 INFO - 'TMOUT': '86400', 07:30:55 INFO - 'USER': 'cltbld', 07:30:55 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:55 INFO - '_': '/tools/buildbot/bin/python'} 07:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:30: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 07:30: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 07:30: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 07:30: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 07:30:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:30:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:30: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 07:30: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 07:30: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 07:30: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 07:30:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:30:57 INFO - warning: no files found matching '*.txt' 07:30:57 INFO - no previously-included directories found matching 'build' 07:30:57 INFO - no previously-included directories found matching 'dist' 07:30:57 INFO - no previously-included directories found matching '.git*' 07:30:57 INFO - Installing collected packages: jsonschema, functools32 07:30:57 INFO - Running setup.py install for functools32 07:30:57 INFO - warning: no files found matching '*.txt' 07:30:57 INFO - no previously-included directories found matching 'build' 07:30:57 INFO - no previously-included directories found matching 'dist' 07:30:57 INFO - no previously-included directories found matching '.git*' 07:30:57 INFO - Successfully installed jsonschema functools32 07:30:57 INFO - Cleaning up... 07:30:57 INFO - Return code: 0 07:30:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30: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')]} 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:30:57 INFO - Using env: {'DISPLAY': ':0', 07:30:57 INFO - 'HOME': '/home/cltbld', 07:30:57 INFO - 'LANG': 'en_US.UTF-8', 07:30:57 INFO - 'LANGUAGE': 'en_US:en', 07:30:57 INFO - 'LOGNAME': 'cltbld', 07:30:57 INFO - 'MAIL': '/var/mail/cltbld', 07:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:57 INFO - 'MOZ_NO_REMOTE': '1', 07:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:57 INFO - 'NO_EM_RESTART': '1', 07:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:57 INFO - 'PWD': '/builds/slave/test', 07:30:57 INFO - 'SHELL': '/bin/bash', 07:30:57 INFO - 'SHLVL': '1', 07:30:57 INFO - 'TERM': 'linux', 07:30:57 INFO - 'TMOUT': '86400', 07:30:57 INFO - 'USER': 'cltbld', 07:30:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:57 INFO - '_': '/tools/buildbot/bin/python'} 07:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:30:57 INFO - Cleaning up... 07:30:57 INFO - Return code: 0 07:30:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:30: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')]} 07:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:30: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')]} 07:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:30:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:30:57 INFO - Using env: {'DISPLAY': ':0', 07:30:57 INFO - 'HOME': '/home/cltbld', 07:30:57 INFO - 'LANG': 'en_US.UTF-8', 07:30:57 INFO - 'LANGUAGE': 'en_US:en', 07:30:57 INFO - 'LOGNAME': 'cltbld', 07:30:57 INFO - 'MAIL': '/var/mail/cltbld', 07:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:30:57 INFO - 'MOZ_NO_REMOTE': '1', 07:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:30:57 INFO - 'NO_EM_RESTART': '1', 07:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:30:57 INFO - 'PWD': '/builds/slave/test', 07:30:57 INFO - 'SHELL': '/bin/bash', 07:30:57 INFO - 'SHLVL': '1', 07:30:57 INFO - 'TERM': 'linux', 07:30:57 INFO - 'TMOUT': '86400', 07:30:57 INFO - 'USER': 'cltbld', 07:30:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:30:57 INFO - '_': '/tools/buildbot/bin/python'} 07:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:30:57 INFO - Downloading/unpacking blobuploader==1.2.4 07:30: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 07:30: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 07:30: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 07:30: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 07:30:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:30:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:30: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 07:30: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 07:30: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 07:30: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 07:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:30:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:30: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 07:30: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 07:30: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 07:30: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 07:30:59 INFO - Downloading docopt-0.6.1.tar.gz 07:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:30:59 INFO - Installing collected packages: blobuploader, requests, docopt 07:30:59 INFO - Running setup.py install for blobuploader 07:30:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:30:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:30:59 INFO - Running setup.py install for requests 07:31:00 INFO - Running setup.py install for docopt 07:31:00 INFO - Successfully installed blobuploader requests docopt 07:31:00 INFO - Cleaning up... 07:31:00 INFO - Return code: 0 07:31:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31: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')]} 07:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31: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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:31: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 07:31: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 07:31:00 INFO - Using env: {'DISPLAY': ':0', 07:31:00 INFO - 'HOME': '/home/cltbld', 07:31:00 INFO - 'LANG': 'en_US.UTF-8', 07:31:00 INFO - 'LANGUAGE': 'en_US:en', 07:31:00 INFO - 'LOGNAME': 'cltbld', 07:31:00 INFO - 'MAIL': '/var/mail/cltbld', 07:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:00 INFO - 'MOZ_NO_REMOTE': '1', 07:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:00 INFO - 'NO_EM_RESTART': '1', 07:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:00 INFO - 'PWD': '/builds/slave/test', 07:31:00 INFO - 'SHELL': '/bin/bash', 07:31:00 INFO - 'SHLVL': '1', 07:31:00 INFO - 'TERM': 'linux', 07:31:00 INFO - 'TMOUT': '86400', 07:31:00 INFO - 'USER': 'cltbld', 07:31:00 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:00 INFO - '_': '/tools/buildbot/bin/python'} 07:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:00 INFO - Running setup.py (path:/tmp/pip-fG2yJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:00 INFO - Running setup.py (path:/tmp/pip-zBlYFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:00 INFO - Running setup.py (path:/tmp/pip-jxkGV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:00 INFO - Running setup.py (path:/tmp/pip-Gvu4eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:00 INFO - Running setup.py (path:/tmp/pip-DILpQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:00 INFO - Running setup.py (path:/tmp/pip-kzJ0xY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:00 INFO - Running setup.py (path:/tmp/pip-d_nwa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:01 INFO - Running setup.py (path:/tmp/pip-dfNStB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:01 INFO - Running setup.py (path:/tmp/pip-PsBSPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:01 INFO - Running setup.py (path:/tmp/pip-Gzaoge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:01 INFO - Running setup.py (path:/tmp/pip-3hEeaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:01 INFO - Running setup.py (path:/tmp/pip-CALWIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:01 INFO - Running setup.py (path:/tmp/pip-PabzUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:01 INFO - Running setup.py (path:/tmp/pip-aaBv_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:01 INFO - Running setup.py (path:/tmp/pip-eHhvo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:01 INFO - Running setup.py (path:/tmp/pip-CuMQNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:01 INFO - Running setup.py (path:/tmp/pip-rY9U1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:01 INFO - Running setup.py install for manifestparser 07:31:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:01 INFO - Running setup.py install for mozcrash 07:31:01 INFO - Running setup.py install for mozdebug 07:31:02 INFO - Running setup.py install for mozdevice 07:31:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Running setup.py install for mozfile 07:31:02 INFO - Running setup.py install for mozhttpd 07:31:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Running setup.py install for mozinfo 07:31:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Running setup.py install for mozInstall 07:31:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Running setup.py install for mozleak 07:31:02 INFO - Running setup.py install for mozlog 07:31:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Running setup.py install for moznetwork 07:31:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:02 INFO - Running setup.py install for mozprocess 07:31:02 INFO - Running setup.py install for mozprofile 07:31:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:03 INFO - Running setup.py install for mozrunner 07:31:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:03 INFO - Running setup.py install for mozscreenshot 07:31:03 INFO - Running setup.py install for moztest 07:31:03 INFO - Running setup.py install for mozversion 07:31:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:03 INFO - Cleaning up... 07:31:03 INFO - Return code: 0 07:31:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31: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')]} 07:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31: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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:31: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 07:31: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 07:31:03 INFO - Using env: {'DISPLAY': ':0', 07:31:03 INFO - 'HOME': '/home/cltbld', 07:31:03 INFO - 'LANG': 'en_US.UTF-8', 07:31:03 INFO - 'LANGUAGE': 'en_US:en', 07:31:03 INFO - 'LOGNAME': 'cltbld', 07:31:03 INFO - 'MAIL': '/var/mail/cltbld', 07:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:03 INFO - 'MOZ_NO_REMOTE': '1', 07:31:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:03 INFO - 'NO_EM_RESTART': '1', 07:31:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:03 INFO - 'PWD': '/builds/slave/test', 07:31:03 INFO - 'SHELL': '/bin/bash', 07:31:03 INFO - 'SHLVL': '1', 07:31:03 INFO - 'TERM': 'linux', 07:31:03 INFO - 'TMOUT': '86400', 07:31:03 INFO - 'USER': 'cltbld', 07:31:03 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:03 INFO - '_': '/tools/buildbot/bin/python'} 07:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:03 INFO - Running setup.py (path:/tmp/pip-Qe8jof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:03 INFO - Running setup.py (path:/tmp/pip-8TAjpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:03 INFO - Running setup.py (path:/tmp/pip-B_TKV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:03 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)) 07:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:03 INFO - Running setup.py (path:/tmp/pip-5R9Yr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:04 INFO - Running setup.py (path:/tmp/pip-8BwSoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:04 INFO - Running setup.py (path:/tmp/pip-f9F6pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:04 INFO - Running setup.py (path:/tmp/pip-vUhpXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:04 INFO - Running setup.py (path:/tmp/pip-RTzIvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:04 INFO - Running setup.py (path:/tmp/pip-Li8bGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:04 INFO - Running setup.py (path:/tmp/pip-22rlKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:04 INFO - Running setup.py (path:/tmp/pip-wLWixk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:04 INFO - Running setup.py (path:/tmp/pip-_l5eEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31: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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:04 INFO - Running setup.py (path:/tmp/pip-M6skYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:04 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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:04 INFO - Running setup.py (path:/tmp/pip-dXo14e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:04 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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:04 INFO - Running setup.py (path:/tmp/pip-2L8m4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:04 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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:04 INFO - Running setup.py (path:/tmp/pip-trBWzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:04 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)) 07:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:04 INFO - Running setup.py (path:/tmp/pip-nbvV4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:04 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)) 07:31:04 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)) 07:31:04 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)) 07:31:04 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)) 07:31:04 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)) 07:31:04 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)) 07:31:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:06 INFO - Downloading blessings-1.6.tar.gz 07:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:06 INFO - Installing collected packages: blessings 07:31:06 INFO - Running setup.py install for blessings 07:31:06 INFO - Successfully installed blessings 07:31:06 INFO - Cleaning up... 07:31:06 INFO - Return code: 0 07:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:06 INFO - Reading from file tmpfile_stdout 07:31:06 INFO - Current package versions: 07:31:06 INFO - argparse == 1.2.1 07:31:06 INFO - blessings == 1.6 07:31:06 INFO - blobuploader == 1.2.4 07:31:06 INFO - docopt == 0.6.1 07:31:06 INFO - functools32 == 3.2.3-2 07:31:06 INFO - jsonschema == 2.5.1 07:31:06 INFO - manifestparser == 1.1 07:31:06 INFO - mozInstall == 1.12 07:31:06 INFO - mozcrash == 1.0 07:31:06 INFO - mozdebug == 0.1 07:31:06 INFO - mozdevice == 0.50 07:31:06 INFO - mozfile == 1.2 07:31:06 INFO - mozhttpd == 0.7 07:31:06 INFO - mozinfo == 0.9 07:31:06 INFO - mozleak == 0.1 07:31:06 INFO - mozlog == 3.4 07:31:06 INFO - moznetwork == 0.27 07:31:06 INFO - mozprocess == 0.25 07:31:06 INFO - mozprofile == 0.28 07:31:06 INFO - mozrunner == 6.13 07:31:06 INFO - mozscreenshot == 0.1 07:31:06 INFO - mozsystemmonitor == 0.3 07:31:06 INFO - moztest == 0.8 07:31:06 INFO - mozversion == 1.4 07:31:06 INFO - psutil == 3.1.1 07:31:06 INFO - requests == 1.2.3 07:31:06 INFO - wsgiref == 0.1.2 07:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31: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')]} 07:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31: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 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:31: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 07:31: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 07:31:06 INFO - Using env: {'DISPLAY': ':0', 07:31:06 INFO - 'HOME': '/home/cltbld', 07:31:06 INFO - 'LANG': 'en_US.UTF-8', 07:31:06 INFO - 'LANGUAGE': 'en_US:en', 07:31:06 INFO - 'LOGNAME': 'cltbld', 07:31:06 INFO - 'MAIL': '/var/mail/cltbld', 07:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:06 INFO - 'MOZ_NO_REMOTE': '1', 07:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:06 INFO - 'NO_EM_RESTART': '1', 07:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:06 INFO - 'PWD': '/builds/slave/test', 07:31:06 INFO - 'SHELL': '/bin/bash', 07:31:06 INFO - 'SHLVL': '1', 07:31:06 INFO - 'TERM': 'linux', 07:31:06 INFO - 'TMOUT': '86400', 07:31:06 INFO - 'USER': 'cltbld', 07:31:06 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:06 INFO - '_': '/tools/buildbot/bin/python'} 07:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 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)) 07:31:06 INFO - Cleaning up... 07:31:06 INFO - Return code: 0 07:31:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31: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')]} 07:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ba1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2886a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a2c770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', '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 07:31:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:31:06 INFO - Using env: {'DISPLAY': ':0', 07:31:06 INFO - 'HOME': '/home/cltbld', 07:31:06 INFO - 'LANG': 'en_US.UTF-8', 07:31:06 INFO - 'LANGUAGE': 'en_US:en', 07:31:06 INFO - 'LOGNAME': 'cltbld', 07:31:06 INFO - 'MAIL': '/var/mail/cltbld', 07:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:06 INFO - 'MOZ_NO_REMOTE': '1', 07:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:06 INFO - 'NO_EM_RESTART': '1', 07:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:06 INFO - 'PWD': '/builds/slave/test', 07:31:06 INFO - 'SHELL': '/bin/bash', 07:31:06 INFO - 'SHLVL': '1', 07:31:06 INFO - 'TERM': 'linux', 07:31:06 INFO - 'TMOUT': '86400', 07:31:06 INFO - 'USER': 'cltbld', 07:31:06 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:06 INFO - '_': '/tools/buildbot/bin/python'} 07:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:31:06 INFO - Cleaning up... 07:31:06 INFO - Return code: 0 07:31:06 INFO - Running post-action listener: _resource_record_post_action 07:31:06 INFO - Running post-action listener: _start_resource_monitoring 07:31:07 INFO - Starting resource monitoring. 07:31:07 INFO - [mozharness: 2017-05-12 14:31:07.024973Z] Finished create-virtualenv step (success) 07:31:07 INFO - [mozharness: 2017-05-12 14:31:07.025439Z] Running install step. 07:31:07 INFO - Running pre-action listener: _resource_record_pre_action 07:31:07 INFO - Running main action method: install 07:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:07 INFO - Reading from file tmpfile_stdout 07:31:07 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:07 INFO - Reading from file tmpfile_stdout 07:31:07 INFO - Output received: 07:31:07 INFO - Usage: mozinstall [options] installer 07:31:07 INFO - Options: 07:31:07 INFO - -h, --help show this help message and exit 07:31:07 INFO - -d DEST, --destination=DEST 07:31:07 INFO - Directory to install application into. [default: 07:31:07 INFO - "/builds/slave/test"] 07:31:07 INFO - --app=APP Application being installed. [default: firefox] 07:31:07 INFO - mkdir: /builds/slave/test/build/application 07:31: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'] 07:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:31:23 INFO - Reading from file tmpfile_stdout 07:31:23 INFO - Output received: 07:31:23 INFO - /builds/slave/test/build/application/firefox/firefox 07:31:23 INFO - Running post-action listener: _resource_record_post_action 07:31:23 INFO - [mozharness: 2017-05-12 14:31:23.309261Z] Finished install step (success) 07:31:23 INFO - [mozharness: 2017-05-12 14:31:23.309504Z] Running run-tests step. 07:31:23 INFO - Running pre-action listener: _resource_record_pre_action 07:31:23 INFO - Running main action method: run_tests 07:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:31:23 INFO - Python 2.7.3 07:31:23 INFO - Return code: 0 07:31:23 INFO - grabbing minidump binary from tooltool 07:31: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')]} 07:31: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 0x2a29a30>, '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 0x2a2a7f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a2aca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:31: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 07:31: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 07:31: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 07:31:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:31:23 INFO - Return code: 0 07:31:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:31:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:31:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:31:23 INFO - ENV: RUST_BACKTRACE is now 1 07:31:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:31:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:31: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/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 07:31: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/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 07:31:23 INFO - Using env: {'DISPLAY': ':0', 07:31:23 INFO - 'HOME': '/home/cltbld', 07:31:23 INFO - 'LANG': 'en_US.UTF-8', 07:31:23 INFO - 'LANGUAGE': 'en_US:en', 07:31:23 INFO - 'LOGNAME': 'cltbld', 07:31:23 INFO - 'MAIL': '/var/mail/cltbld', 07:31:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:23 INFO - 'MOZ_NO_REMOTE': '1', 07:31:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:23 INFO - 'NO_EM_RESTART': '1', 07:31:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:23 INFO - 'PWD': '/builds/slave/test', 07:31:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:23 INFO - 'RUST_BACKTRACE': '1', 07:31:23 INFO - 'SHELL': '/bin/bash', 07:31:23 INFO - 'SHLVL': '1', 07:31:23 INFO - 'TERM': 'linux', 07:31:23 INFO - 'TMOUT': '86400', 07:31:23 INFO - 'USER': 'cltbld', 07:31:23 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622', 07:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:23 INFO - '_': '/tools/buildbot/bin/python'} 07:31: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/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 07:31:23 INFO - mozversion application_buildid: 20170512140107 07:31:23 INFO - mozversion application_changeset: 1ec1d88637208098802e57d13db9033902e5a229 07:31:23 INFO - mozversion application_display_name: Nightly 07:31:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:31:23 INFO - mozversion application_name: Firefox 07:31:23 INFO - mozversion application_remotingname: firefox 07:31:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:31:23 INFO - mozversion application_vendor: Mozilla 07:31:23 INFO - mozversion application_version: 55.0a1 07:31:23 INFO - mozversion platform_buildid: 20170512140107 07:31:23 INFO - mozversion platform_changeset: 1ec1d88637208098802e57d13db9033902e5a229 07:31:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:31:23 INFO - mozversion platform_version: 55.0a1 07:31:23 INFO - using testdate: 1494599483 07:31:23 INFO - actual date: 1494599483 07:31:23 INFO - starting webserver on 'localhost:58095' 07:31:23 INFO - SUITE-START | Running 2 tests 07:31:23 INFO - TEST-START | dromaeo_css 07:31:23 INFO - operating with platform_type : linux_ 07:31:23 INFO - Initialising browser for dromaeo_css test... 07:31:23 INFO - TEST-INFO | started process 26067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpznzZqX/profile http://localhost:58095/getInfo.html) 07:31:27 INFO - PID 26067 | __metrics Screen width/height:1600/1200 07:31:27 INFO - PID 26067 | colorDepth:24 07:31:27 INFO - PID 26067 | Browser inner width/height: 1024/695 07:31:27 INFO - PID 26067 | __metrics 07:31:28 INFO - PID 26067 | 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" 07:31:28 INFO - PID 26067 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:31:29 INFO - TEST-INFO | 26067: exit 0 07:31:29 INFO - Browser initialized. 07:31:29 INFO - Running cycle 1/1 for dromaeo_css test... 07:31:29 INFO - TEST-INFO | started process 26166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpznzZqX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:41 INFO - PID 26166 | RSS: Main: 152186880 07:31:41 INFO - PID 26166 | 07:34:48 INFO - PID 26166 | Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58095/tests/dromaeo/cssquery-ext.html) 07:34:49 INFO - PID 26166 | RSS: Main: 259530752 07:34:49 INFO - PID 26166 | 07:37:51 INFO - PID 26166 | Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-ext.html (next: http://localhost:58095/tests/dromaeo/cssquery-jquery.html) 07:37:52 INFO - PID 26166 | RSS: Main: 226906112 07:37:52 INFO - PID 26166 | 07:40:54 INFO - PID 26166 | Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58095/tests/dromaeo/cssquery-mootools.html) 07:40:54 INFO - PID 26166 | RSS: Main: 179867648 07:40:54 INFO - PID 26166 | 07:43:57 INFO - PID 26166 | Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58095/tests/dromaeo/cssquery-prototype.html) 07:43:57 INFO - PID 26166 | RSS: Main: 176484352 07:43:57 INFO - PID 26166 | 07:47:00 INFO - PID 26166 | Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58095/tests/dromaeo/cssquery-yui.html) 07:47:01 INFO - PID 26166 | RSS: Main: 179392512 07:47:01 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | Cycle 1(1): loaded http://localhost:58095/tests/dromaeo/cssquery-yui.html (next: http://localhost:58095/tests/dromaeo/cssquery-dojo.html) 07:50:03 INFO - PID 26166 | RSS: Main: 275595264 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | __start_tp_report 07:50:03 INFO - PID 26166 | _x_x_mozilla_page_load 07:50:03 INFO - PID 26166 | _x_x_mozilla_page_load_details 07:50:03 INFO - PID 26166 | |i|pagename|runs| 07:50:03 INFO - PID 26166 | |0;dojo.html;2669.5328317544427;2707.350235943374;2747.931301717457;2747.9450410991785;2750.174947515745;5056.292119103319;5171.276021357013;5216.347956505437;5251.947480525198;5299.814506492274;5207.921881171786;5222.5038621331005;5223.425423203445;5297.655652382592;5305.363356397228;4337.371081193225;4558.27067669173;4640.3967484227005;4682.274247491644;4760.404949381327;4647.88380290492;4702.553257440538;4716.127516409458;4718.787654555536;4793.400824896888;4570.085982803453;4610.907781844345;4650.209464391061;4650.25595904657;4653.208954477747;4614.838480653195;4663.0207656392195;4674.929876051828;4726.503717109723;4732.313814496879;4624.3988281523525;4638.281066434708;4657.371254880615;4662.230731929228;4666.136764698507;5116.232565115262;5193.143131383317;5237.685738855643;5238.816641417532;5291.576673866081;5559.360673522557;5571.247881536022;5571.609987300891;5576.414476479992;5577;5655.236543066674;5727.255456790591;5738.77044918207;5742.712864356766;5745.425457454263;5273.762680679379;5307.708076055854;5319.787208511693;5323.920141197885;5324.281222035053;5718.285214348207;5810.709464526757;5819.388964158702;5824.650520968756;5829.854253643693;5374.677519348852;5456.48163424474;5460.399356070829;5466.262054622575;5578.832635020955;5602.439756024365;5615.354234263031;5615.8596035098635;5659.745311460994;5664.518515926193;5468.781248750005;5489.58828087895;5569.526590239875;5571.331440227118;5587.078132108238;5617.859553511195;5639;5649;5649.237352957381;5674.177244299553;4769.093872164278;4776.14029474691;4779.06808172403;4780.68925519847;4823.782929768169;3990.580988996114;4021.8994525137105;4044.9797751011056;4049.331860243086;4111.753294802262;4814.975925120352;4818.68678535901;4847.515248475195;4859.271109333557;4864.537868902448;5684.232628595169;5688.317401911797;5688.857778555486;5695;5743.971280143573;4688.929666055012;4762.642801789811;4801.4718380978065;4803.423589169322;4809.5430934061205;5008.524190201925;5087.4149472811105;5142.742862856842;5143.8456846294675;5151.716655583904;5224.399194092728;5244.501772331622;5248.212768084818;5249.343832020997;5250.65870718402;5517.641353312182;5679.17651940458;5691.544676425959;5700.3444603871085;5705.030144875298;5105.387353517602;5219.2693022976055;5219.425863155126;5232.319798426181;5245.895082098413;5587.189857470726;5590.692511911884;5591.10542313228;5593.776248949996;5634;5475.698836564027;5553.416891226363;5651.971740141273;5656.886862262814;5704.600677952503;5454.181872719124;5460.535854452257;5516.365617953827;5558.527525160407;5563.13771365439;5312.601554883322;5409.86475338104;5438.293021907127;5495.450454954473;5499.807506737245;5343.332083489564;5366;5369.597280203922;5400.675959442446;5408.107662235687;5481.205225242397;5506.03644362243;5507.173923911445;5507.642003269774;5509.669419834822;5267.420583735862;5342.278792362981;5345.251664766858;5352.357717073976;5402.378726446509 07:50:03 INFO - PID 26166 | |1;ext.html;645.7320212111973;690.5787469643518;718.5939943931678;1023.4780262066344;1035.6012935020015;25246;32217.51673724896;32281.19297017573;32364.19089522763;32370.144478328293;34862.3027539449;35012;35169.12077198071;35216.94349169522;35265.64734352662;5199.87000324993;6972.1284839395075;7103.751368702096;7118.608476533788;7119.644017799115;5278.181881808326;5354.866128346784;5355.196720491918;6295.874082518347;6997.545159564637;7077.3984211342095;7099.254578269259;7116.288371162894;7122.8575428491395;7171.605561694105;7021;7026.789196324067;7062.57624542524;7073.787786366366;7082.220955694844;6969.989351544048;7019.859602807966;7067.363937245621;7073.681684324218;7079.185893622248;30597.694023059707;30732.53901191484;30789.538156927665;30820.691793082005;30840.383192336252;2997.3405850713025;3025.0319897632767;3025.7428118609946;3031.4846476099115;3031.9696803031907;24102.156424525234;24127;24203.063754899566;24219.273421797374;24231.636525452133;3066.1721335239586;3105.3168302973536;3117.610298712661;3126.6716994715684;3134.514150306706;137435.31282343625;137723;138413;138447;139089;40719.59280407158;40723.796381017906;40829.79585102115;40830;40834.38748418776;42320.017197162124;42461.78769106135;42552.361714574305;42580.78709606432;42633.78683106565;11775.823362649568;11854.94072529632;11859;11879.22785018971;11887.227330223508;9415.246780257563;9430.245580353556;9436.952815236018;9441.15029647335;9444.716658500256;9906.80186396268;10131.645392411378;11569.42152892352;11886.762264754656;12154.756904861853;4738.786754596052;4748.477667456577;4878.853634390974;4879.902401952012;4929.630277729185;835.2691395029349;893.4237416866085;898.8112496375834;924.3806649544821;931.9953400233092;20329.49176270605;20527;21022;21151.682724759143;21310.467238319165;966.5119114846957;975.687779910436;985.3004366899438;994.1798016636304;1003.1423133220991;10755.731106722395;11180.552777888794;11188.66434006981;11189.944050279697;11297;150890;150915;151141;151818;152057;39641.60358396437;39717;39729.20541589152;39789.2042159161;39870;1185.4418398680286;1219.9878001219874;1232;1236.5177580743518;1238.1518659718122;19160.425187244404;19987.500312492302;20320.390388288375;20656.690149648068;20900.1639934407;29297.85351073231;29346.559801602565;29361.55957660594;29438.11685649434;29449.85275073611;143788;144357;144477;144602;145018;28626.57060144141;28668.139955801358;28795.42409151847;28827.135185944455;28893.855530722212;28986.42027159487;29931.55102673418;32211.67788322087;33951.4907276396;35444;25298.620520692573;26295.474090518466;26431.735682642928;27638.170854874385;27953.72046279511;27039.918403263648;27177.86411067932;28202.576961346007;28224.85887570549;28987.7101228985;31040.0687979361;31050.84474577703;31066;31133.53299700461;31137;26138.86930565335;26301.712285964255;26586.803593837936;27207;27353.520408673376;21124.366269011953;21940.341789746333;22115;22136.667949981085;22177.556448870608 07:50:03 INFO - PID 26166 | |2;jquery.html;405.1855769902495;411.2494697177652;425.5212885503808;438.57458265482484;439.9714018588792;15396.461123860667;16176.676466470679;16920;16995.575110622227;16997;17273.13634318282;17391.217395217212;17624.383146589873;17944.730829037573;18093.366732164377;5239.21411788231;5303.602229832759;5424.918626220609;5443.727813609324;5450.754716037788;5367.865803354928;5472;5474.206240095204;5483.150111732688;5483.616146869721;5226.608004399666;5310.4424035476295;5448.945510544883;5450.236966824647;5481.643693159952;5338.092524270864;5359;5380.704061276629;5397.325334333228;5403.027455058088;4835.1296766582;4877.365942427497;4887.462379138327;4892.021595680878;4896.045271172123;20802.895985519976;20822.58354832895;20902;20993.580128397345;21005.68491472629;1660.8090069642026;1719.759233707282;1726.9136543172917;1727.5162954372795;1727.7149270370373;13649.385777640031;14413;14444.422223111063;14488.348024338824;14524.273786310641;2027.3208475160839;2044.37646517812;2106.3575609439113;2110.9788902110936;2120.90455929482;77808.61095694486;78256.21743782546;78284.21715782826;79156.60421697912;81392;10739.248252622241;10912.656165665481;10985.285956412808;11035.503402346914;11298.491567879466;15817.288222030036;15862.682746345008;15869.04785712861;15923.52229433119;15944.681106377808;7159.122687867008;7206.863866006066;7321.194668586451;7324.267573242633;7345.15530713964;6392.904106438407;6464.159659244268;6635.203775546869;6646.4350530205475;6651.534392592472;6780.254172041072;6931.861362772817;7011.649417529103;7055.753048643272;7080.398166155832;1999;2001.0895042755512;2038.7349644546114;2063.298478517281;2070.3995841205883;843.7510934274404;893.8033632600817;898.955052247385;903.6295119001178;911.7128104647015;17431.825681743274;17601.207945642476;17759.200835962154;17990.44684436737;18090.728639070425;867.3234876796109;891.7681402835309;897.7620930453435;905.800723840754;911.6262332443666;9245.861312080324;9371.484568348806;9468.14786669203;9745.805083898282;9756.268279879037;336613;340193.6719175706;347833;348028;349354;70298;73348;73841.6307918457;74528.62735686287;75462;1023.8157131716363;1035.089121573011;1069.545443186658;1073.388168743824;1075.381655548063;14445;14578.271086445424;14632.853671463576;14659.92670036643;15173.241337932839;36922.8153859229;37012.152751741014;37380.626193738804;37660.62339376681;37960.430593540565;45628.54371456243;45970;47837.91566133446;48543.271850921556;48841;7958.442908996315;8039.879401809095;8212.83574328522;8256.917430825615;8281.37889658266;11742.88257117418;11916.344601047025;11928.76142477163;12301.32342721159;12325.876741232833;10585.8941410588;10997.725056873322;11120.61077862271;11202.215844891105;11687.682104642197;10402.791944161227;10597.576096956036;10780.622678206539;10823.67528974132;10853.728656783644;11551.537938482703;11554;11604;11725.88274117282;11733.237339572901;8496.150384961702;8505.149485051692;8593.742187734379;8637.136286371313;8708.651653933772;10545.420001899662;10559.63041293551;10583.841242381524;10664.200184986312;11049.502772375423 07:50:03 INFO - PID 26166 | |3;mootools.html;1089.7548051688375;1230.464747834692;1274.108124312981;1289.1040726694946;1292.7866901961177;8275.503469791816;10285.897141028587;10634.57461701531;10643.627473038427;10656.573737050508;10416.843747343795;10528.684139475828;10529.315594486354;10531.473426328672;10541;1787.4012205911035;2187.223535644847;2218.279059305724;2219.5671843990426;2225.610518159324;1925.2491528303972;2169.1215057901577;2245.5733410652006;2266.036934302923;2279.794818466338;2180.9563808723815;2190.4742861713235;2190.496185877249;2205.0297868937696;2208.7901649343366;2121.1621409543222;2132.1577976699273;2132.4029271803765;2133.4666333416644;2134.039682143034;2107.093949601671;2119.5231073008604;2130.041481333406;2145.2062736787334;2185.4645611825044;9713.90286097144;9798.314118011742;9806.754831129207;9810.264230182765;9822.75443113928;5134.460881607452;5313.3092697949405;5314.777601151718;5323.361196656388;5328.520433161034;3182.931094788406;3310.6027276726704;3335.0495108894092;3365.2428203654226;3369.6630336966355;1112.821948488246;1150.654803558929;1182.0425455381169;1189.0190592761007;1191.5650787462514;15200.391984320613;15208.467703630431;15210.847891521165;15214.847851521454;15217.61955951099;4929.457759646436;5019.47295533971;5178.922316165261;5252.632315737938;5294.708791016454;12714.809277860839;12718.427670754838;12725.363731813371;12732.363381830872;12734.299613521345;5812.941870581325;5813.418658134237;5819.127130930395;5828.358880523139;5839.386864379264;4981.626378021663;5088;5144.588432925357;5146.125158723026;5172;4019.8191081401405;4082.897927551835;4139.19285739278;4155.065110350147;4213.115245798355;1312.4487715159655;1315.5658632651202;1342.6506361107024;1343.8454577723492;1352.4860552989999;670.8691805097959;696.7143471176793;696.8292768271705;711.5161690050816;721.3940290156294;5519.69641669712;5724.16999535065;5743.368229494752;5789.189513468118;5945.821625351246;522.1671434062664;536.9221462887859;539.4524557574066;801.7314199743034;832.9250367466957;3566.465030245432;3629.3467175908063;3651.4887915691825;3718.535183102112;3765.1151979284846;12221.144519883701;12500.499980000699;12691.682707932376;12714.745705085847;12760.936195318964;12548.435320410603;12633.305168215837;12667.746645067231;12667.873321266854;12763.808542871864;7183.892241616275;7217.530860494051;7354.043974283309;7363.889541656879;7375.741849035258;5307.336582927134;5348.679079255257;5358.866028349323;5428.402875683751;5557.805476808292;11395.202335836411;11396.94301528487;11427;11480.655580332603;11632.243904146204;9444.763880903032;11253.606123785628;11502.424878756196;11614.361210133524;12001.939990299992;7895.921040789519;8112.026556812984;8114.756557303291;8125.512469251864;8127.715529956659;8739.21347078766;9027.729168124966;9563.330566860532;9757.21942244633;9813.509324533887;8192.713255036284;8392.790180245544;8424.536650484282;8449.577521124042;8579.785505362415;8143.5928203590765;8220.506769593843;8227.917720822716;8424.326053915549;8480;7416.851662966818;7495.550266983998;7502.512336698315;7517.774466766005;7554.848903022019;5040.7479626019285;5078.619103567321;5171.379434467888;5195.350581177353;5239.633225674319;5246.737663116905;5323.920141197807;5365.490278423544;5385.9192112119135;5443.863903402446 07:50:03 INFO - PID 26166 | |4;prototype.html;384.3331819293525;386.44738024624786;387.35118676217337;389.82652719539783;390.20203683467304;5230.503102205289;5301.204819277111;5303.920441193385;5332.280142180804;5346.117890548065;5062.468440813716;5150.896982060366;5176.171812509999;5217.58259339252;5230.137027390486;4088.1619268049976;4098.159877225176;4124.876253712393;4129.339305711094;4132.938005929914;4117.3206420940505;4121.814518346681;4125.257453658341;4132.173565286939;4144.606262405082;4032.3951407288996;4057.660971879273;4092.3247664135383;4107.240160570304;4120.896977575555;4071.7149799514045;4073.6333729964445;4079.877603671865;4084.5711200323835;4103.107309346919;3695.7043436525014;3696.2977034363385;3702.2965636529243;3714.888553343404;3717.70258379329;5122.538971492546;5125.923111153335;5127.87180320495;5132.5124113209185;5134;4738.620910327166;4894.388201474816;4945.629077819178;4949.653524253304;4953.182724850395;5605.859853503654;5624.887502249973;5652.8869422611315;5655.773769049233;5670.801521946753;4178.26880295947;4268.274393353137;4272.252355837747;4281.721688090295;4291.098869237433;5636.943630563683;5824.912626310609;5884.146798714205;5903.291605007384;5953.970230148865;4341.82632694695;4421.38100665907;4423.778811059498;4440.977795111004;4471.821127154877;4495.325701144854;4541.409616749801;4544.931826022612;4548.931766023512;4551.431071116111;4494;4740.715557066518;4751.03060587133;4999.47505511916;5000;5518.117101263779;5623.325200975909;5624.71876406178;5628.774849005994;5646;1553.5572361876812;1574.1184936435632;1576.7398379267522;1577.4321244352025;1580.6601580660213;1092.9781404371868;1131.8698349689892;1142.5829572206085;1147.5180424221846;1148.3454430974432;4881.8535443936735;4901.240307752304;4915.21356582945;4933.753312334369;4940.098062246061;5708.65748055118;5765.019946609086;5765.048766953406;5784.739686714024;5796.203177015139;4645.581897629162;4872.4883887192045;4911.803527858846;4912.754362281871;4955.479674634112;3063.862126204326;3087.2899233176495;3091.969080309213;3092.1805721483825;3104.7205751482024;5299.258103865462;5342.305500284938;5400.351957765093;5406.675599463966;5411.350637923474;5941.287045554561;5946.43508866657;5968.432998865101;5975.820725378246;5987.221661184019;5368.087425137736;5383.380911195184;5387.626155330444;5388.622796404293;5391.406945236099;5673.716314184357;5765;5814.534837212928;5841.6856207353;5843.14763133861;5447.12845944647;5683.60214784961;5717.599768016199;5730.656160630376;5731.996900542305;5093.239627840109;5238.842834714965;5260.316158899319;5268.6575372600655;5278.86802829917;3872.806359682061;3990.7605543667473;4015.5582885881945;4028.91942161161;4035.1122752994293;5637.08106488194;5773.422657734361;5789.739461724317;5817.069268916953;5830.067189249699;5630.915536266877;5634.126710359901;5635.351934527582;5656.406077361982;5657.179708942263;5627.184058311604;5647.435256474319;5652.943470565242;5669.659820410789;5677.943220567741;5451.972740136274;5502.202180683858;5530.6128570999645;5532.336119665666;5543.085390910454;5673.914891276717;5681.289838770153;5684.118961560965;5689.7439615218245;5694.715264236855;5401.702906340031;5502.944970550243;5505.917411238915;5508.724563771715;5529.22590837275 07:50:03 INFO - PID 26166 | |5;yui.html;700.6951975890491;724.235931092697;734.808949673085;742.465424894076;749.99625001875;13001.67495812604;13883.722325553495;13903.443862245498;13926;13963.37164827584;13633.659158521043;15759.842401575981;16193.190340482988;16255.674886502264;16337.428190013352;885.809550946548;903.7288813355967;908.6410867707252;909.2998391238742;909.3089252168367;873.6243415331405;906;908.954552272387;912.2884150362695;912.6486302773446;885.3536918049828;890.0654312971386;890.6036813617943;894.0701670262919;935.0088905759916;879.5294517433175;882.3294296334767;890.2388457868494;890.9242714369221;934.2712684106408;678.220046946016;681.0329332348077;685.7222824755931;687.0861753867345;687.3229868579446;13544.322783860865;13919.373628186755;14026;14144.504942327072;14153;15314.310856011507;15436.691266174614;15757;15758.369665213379;15774.132422716746;12553.184043037174;12790.232586044775;12799.74400511994;12799.87200127996;12804.551840685624;2919.2701824543865;2995.1912983494553;3000.8949686761075;3017.411604737077;3019.818810871333;64441;65093.3490665092;65751.342486575;67107;69979.99999999949;12209.145359824726;12528.624141275777;12548.121631485708;12582.496700132075;12587.24476531411;21460.678089828663;21735;22148.114075436803;22172.889135554542;22200.222992195195;5970.0746384310505;6085.726142323518;6095.2685677718955;6097.2683278006925;6112.7554897804475;2914.7231013053724;2976.5088760354297;2982.3587928595457;2986.3728616990675;2989.4170636726067;3557.2529768748855;3584.175639602906;3610.765300255475;3636.4181730922924;3694.833732482045;654.2672207128047;663.741140955028;670.2158474584747;670.497127154634;672.2739441403272;577.1919312961887;577.6707276852238;581.0818906128308;585.9912101318398;587.7267070812093;8997.775055623531;9047.321450891079;9293.907060929305;9662.3719458235;9825.508724563742;600.5105838741499;606.5572132343326;616.3343588923951;617.0713076819358;625.5121005615628;4369.082492676574;4405.559444055569;4411.338299255073;4432.84485043022;4454.10917816438;59116.704416478504;59952.70023649854;60582.39417605856;61029.69485152634;61643;54254.45745542573;54712;55027;55266;56085.15872261919;48552.75723621359;49919;50239.24641130386;50555.74722126366;50809.49190508122;8896.243819275434;8989.289928748836;9019;9071.455712657262;9300.488473133772;26409.735902641496;26734.197974060808;26927.596086058333;27070.864645676647;27566.862165689043;31728.524072139397;31816.840915795274;31838.840805795822;32032;32073.35853282968;11174;11218.831717524406;11380.317180969168;11482.598109066143;11482.655520334403;12593.559225427067;12709.17390369623;12750.234985900875;12783.872161278268;12928.224306541637;11881.108916831445;12054.276743395425;12085.939570302093;12197.390130493617;12206.962408195403;11661.59184428575;11813.350265735467;11880.227785193934;11922.403879806148;11951.521939122686;10013.849792252977;10326.535305911402;10770.838437423288;10822.621208257673;10837.295575787548;7691.807704807425;7713.961430192813;7861.135275119618;8064.798380040546;8165.14266002085;9423.528823558932;9439.244860411289;9583.952080239555;9634.181094607036;9917.65288214909 07:50:03 INFO - PID 26166 | __end_tp_report 07:50:03 INFO - PID 26166 | __start_cc_report 07:50:03 INFO - PID 26166 | _x_x_mozilla_cycle_collect,1356 07:50:03 INFO - PID 26166 | __end_cc_report 07:50:03 INFO - PID 26166 | __startTimestamp1494600603454__endTimestamp 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | ------- Summary: start ------- 07:50:03 INFO - PID 26166 | Number of tests: 6 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | [#0] dojo.html Cycles:165 Average:5153.79 Median:5307.71 stddev:602.45 (11.4%) stddev-sans-first:571.90 07:50:03 INFO - PID 26166 | Values: 2669.5 2707.4 2747.9 2747.9 2750.2 5056.3 5171.3 5216.3 5251.9 5299.8 5207.9 5222.5 5223.4 5297.7 5305.4 4337.4 4558.3 4640.4 4682.3 4760.4 4647.9 4702.6 4716.1 4718.8 4793.4 4570.1 4610.9 4650.2 4650.3 4653.2 4614.8 4663.0 4674.9 4726.5 4732.3 4624.4 4638.3 4657.4 4662.2 4666.1 5116.2 5193.1 5237.7 5238.8 5291.6 5559.4 5571.2 5571.6 5576.4 5577.0 5655.2 5727.3 5738.8 5742.7 5745.4 5273.8 5307.7 5319.8 5323.9 5324.3 5718.3 5810.7 5819.4 5824.7 5829.9 5374.7 5456.5 5460.4 5466.3 5578.8 5602.4 5615.4 5615.9 5659.7 5664.5 5468.8 5489.6 5569.5 5571.3 5587.1 5617.9 5639.0 5649.0 5649.2 5674.2 4769.1 4776.1 4779.1 4780.7 4823.8 3990.6 4021.9 4045.0 4049.3 4111.8 4815.0 4818.7 4847.5 4859.3 4864.5 5684.2 5688.3 5688.9 5695.0 5744.0 4688.9 4762.6 4801.5 4803.4 4809.5 5008.5 5087.4 5142.7 5143.8 5151.7 5224.4 5244.5 5248.2 5249.3 5250.7 5517.6 5679.2 5691.5 5700.3 5705.0 5105.4 5219.3 5219.4 5232.3 5245.9 5587.2 5590.7 5591.1 5593.8 5634.0 5475.7 5553.4 5652.0 5656.9 5704.6 5454.2 5460.5 5516.4 5558.5 5563.1 5312.6 5409.9 5438.3 5495.5 5499.8 5343.3 5366.0 5369.6 5400.7 5408.1 5481.2 5506.0 5507.2 5507.6 5509.7 5267.4 5342.3 5345.3 5352.4 5402.4 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | [#1] ext.html Cycles:180 Average:28774.70 Median:16831.93 stddev:37308.99 (221.7%) stddev-sans-first:37353.86 07:50:03 INFO - PID 26166 | Values: 645.7 690.6 718.6 1023.5 1035.6 25246.0 32217.5 32281.2 32364.2 32370.1 34862.3 35012.0 35169.1 35216.9 35265.6 5199.9 6972.1 7103.8 7118.6 7119.6 5278.2 5354.9 5355.2 6295.9 6997.5 7077.4 7099.3 7116.3 7122.9 7171.6 7021.0 7026.8 7062.6 7073.8 7082.2 6970.0 7019.9 7067.4 7073.7 7079.2 30597.7 30732.5 30789.5 30820.7 30840.4 2997.3 3025.0 3025.7 3031.5 3032.0 24102.2 24127.0 24203.1 24219.3 24231.6 3066.2 3105.3 3117.6 3126.7 3134.5 137435.3 137723.0 138413.0 138447.0 139089.0 40719.6 40723.8 40829.8 40830.0 40834.4 42320.0 42461.8 42552.4 42580.8 42633.8 11775.8 11854.9 11859.0 11879.2 11887.2 9415.2 9430.2 9437.0 9441.2 9444.7 9906.8 10131.6 11569.4 11886.8 12154.8 4738.8 4748.5 4878.9 4879.9 4929.6 835.3 893.4 898.8 924.4 932.0 20329.5 20527.0 21022.0 21151.7 21310.5 966.5 975.7 985.3 994.2 1003.1 10755.7 11180.6 11188.7 11189.9 11297.0 150890.0 150915.0 151141.0 151818.0 152057.0 39641.6 39717.0 39729.2 39789.2 39870.0 1185.4 1220.0 1232.0 1236.5 1238.2 19160.4 19987.5 20320.4 20656.7 20900.2 29297.9 29346.6 29361.6 29438.1 29449.9 143788.0 144357.0 144477.0 144602.0 145018.0 28626.6 28668.1 28795.4 28827.1 28893.9 28986.4 29931.6 32211.7 33951.5 35444.0 25298.6 26295.5 26431.7 27638.2 27953.7 27039.9 27177.9 28202.6 28224.9 28987.7 31040.1 31050.8 31066.0 31133.5 31137.0 26138.9 26301.7 26586.8 27207.0 27353.5 21124.4 21940.3 22115.0 22136.7 22177.6 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | [#2] jquery.html Cycles:180 Average:23587.14 Median:36967.48 stddev:57214.85 (154.8%) stddev-sans-first:57348.88 07:50:03 INFO - PID 26166 | Values: 405.2 411.2 425.5 438.6 440.0 15396.5 16176.7 16920.0 16995.6 16997.0 17273.1 17391.2 17624.4 17944.7 18093.4 5239.2 5303.6 5424.9 5443.7 5450.8 5367.9 5472.0 5474.2 5483.2 5483.6 5226.6 5310.4 5448.9 5450.2 5481.6 5338.1 5359.0 5380.7 5397.3 5403.0 4835.1 4877.4 4887.5 4892.0 4896.0 20802.9 20822.6 20902.0 20993.6 21005.7 1660.8 1719.8 1726.9 1727.5 1727.7 13649.4 14413.0 14444.4 14488.3 14524.3 2027.3 2044.4 2106.4 2111.0 2120.9 77808.6 78256.2 78284.2 79156.6 81392.0 10739.2 10912.7 10985.3 11035.5 11298.5 15817.3 15862.7 15869.0 15923.5 15944.7 7159.1 7206.9 7321.2 7324.3 7345.2 6392.9 6464.2 6635.2 6646.4 6651.5 6780.3 6931.9 7011.6 7055.8 7080.4 1999.0 2001.1 2038.7 2063.3 2070.4 843.8 893.8 899.0 903.6 911.7 17431.8 17601.2 17759.2 17990.4 18090.7 867.3 891.8 897.8 905.8 911.6 9245.9 9371.5 9468.1 9745.8 9756.3 336613.0 340193.7 347833.0 348028.0 349354.0 70298.0 73348.0 73841.6 74528.6 75462.0 1023.8 1035.1 1069.5 1073.4 1075.4 14445.0 14578.3 14632.9 14659.9 15173.2 36922.8 37012.2 37380.6 37660.6 37960.4 45628.5 45970.0 47837.9 48543.3 48841.0 7958.4 8039.9 8212.8 8256.9 8281.4 11742.9 11916.3 11928.8 12301.3 12325.9 10585.9 10997.7 11120.6 11202.2 11687.7 10402.8 10597.6 10780.6 10823.7 10853.7 11551.5 11554.0 11604.0 11725.9 11733.2 8496.2 8505.1 8593.7 8637.1 8708.7 10545.4 10559.6 10583.8 10664.2 11049.5 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | [#3] mootools.html Cycles:180 Average:6252.68 Median:4147.13 stddev:4006.50 (96.6%) stddev-sans-first:3998.95 07:50:03 INFO - PID 26166 | Values: 1089.8 1230.5 1274.1 1289.1 1292.8 8275.5 10285.9 10634.6 10643.6 10656.6 10416.8 10528.7 10529.3 10531.5 10541.0 1787.4 2187.2 2218.3 2219.6 2225.6 1925.2 2169.1 2245.6 2266.0 2279.8 2181.0 2190.5 2190.5 2205.0 2208.8 2121.2 2132.2 2132.4 2133.5 2134.0 2107.1 2119.5 2130.0 2145.2 2185.5 9713.9 9798.3 9806.8 9810.3 9822.8 5134.5 5313.3 5314.8 5323.4 5328.5 3182.9 3310.6 3335.0 3365.2 3369.7 1112.8 1150.7 1182.0 1189.0 1191.6 15200.4 15208.5 15210.8 15214.8 15217.6 4929.5 5019.5 5178.9 5252.6 5294.7 12714.8 12718.4 12725.4 12732.4 12734.3 5812.9 5813.4 5819.1 5828.4 5839.4 4981.6 5088.0 5144.6 5146.1 5172.0 4019.8 4082.9 4139.2 4155.1 4213.1 1312.4 1315.6 1342.7 1343.8 1352.5 670.9 696.7 696.8 711.5 721.4 5519.7 5724.2 5743.4 5789.2 5945.8 522.2 536.9 539.5 801.7 832.9 3566.5 3629.3 3651.5 3718.5 3765.1 12221.1 12500.5 12691.7 12714.7 12760.9 12548.4 12633.3 12667.7 12667.9 12763.8 7183.9 7217.5 7354.0 7363.9 7375.7 5307.3 5348.7 5358.9 5428.4 5557.8 11395.2 11396.9 11427.0 11480.7 11632.2 9444.8 11253.6 11502.4 11614.4 12001.9 7895.9 8112.0 8114.8 8125.5 8127.7 8739.2 9027.7 9563.3 9757.2 9813.5 8192.7 8392.8 8424.5 8449.6 8579.8 8143.6 8220.5 8227.9 8424.3 8480.0 7416.9 7495.6 7502.5 7517.8 7554.8 5040.7 5078.6 5171.4 5195.4 5239.6 5246.7 5323.9 5365.5 5385.9 5443.9 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | [#4] prototype.html Cycles:180 Average:4682.48 Median:5133.26 stddev:1317.78 (25.7%) stddev-sans-first:1281.38 07:50:03 INFO - PID 26166 | Values: 384.3 386.4 387.4 389.8 390.2 5230.5 5301.2 5303.9 5332.3 5346.1 5062.5 5150.9 5176.2 5217.6 5230.1 4088.2 4098.2 4124.9 4129.3 4132.9 4117.3 4121.8 4125.3 4132.2 4144.6 4032.4 4057.7 4092.3 4107.2 4120.9 4071.7 4073.6 4079.9 4084.6 4103.1 3695.7 3696.3 3702.3 3714.9 3717.7 5122.5 5125.9 5127.9 5132.5 5134.0 4738.6 4894.4 4945.6 4949.7 4953.2 5605.9 5624.9 5652.9 5655.8 5670.8 4178.3 4268.3 4272.3 4281.7 4291.1 5636.9 5824.9 5884.1 5903.3 5954.0 4341.8 4421.4 4423.8 4441.0 4471.8 4495.3 4541.4 4544.9 4548.9 4551.4 4494.0 4740.7 4751.0 4999.5 5000.0 5518.1 5623.3 5624.7 5628.8 5646.0 1553.6 1574.1 1576.7 1577.4 1580.7 1093.0 1131.9 1142.6 1147.5 1148.3 4881.9 4901.2 4915.2 4933.8 4940.1 5708.7 5765.0 5765.0 5784.7 5796.2 4645.6 4872.5 4911.8 4912.8 4955.5 3063.9 3087.3 3092.0 3092.2 3104.7 5299.3 5342.3 5400.4 5406.7 5411.4 5941.3 5946.4 5968.4 5975.8 5987.2 5368.1 5383.4 5387.6 5388.6 5391.4 5673.7 5765.0 5814.5 5841.7 5843.1 5447.1 5683.6 5717.6 5730.7 5732.0 5093.2 5238.8 5260.3 5268.7 5278.9 3872.8 3990.8 4015.6 4028.9 4035.1 5637.1 5773.4 5789.7 5817.1 5830.1 5630.9 5634.1 5635.4 5656.4 5657.2 5627.2 5647.4 5652.9 5669.7 5677.9 5452.0 5502.2 5530.6 5532.3 5543.1 5673.9 5681.3 5684.1 5689.7 5694.7 5401.7 5502.9 5505.9 5508.7 5529.2 07:50:03 INFO - PID 26166 | 07:50:03 INFO - PID 26166 | [#5] yui.html Cycles:180 Average:14400.84 Median:49235.88 stddev:17265.36 (35.1%) stddev-sans-first:17283.14 07:50:03 INFO - PID 26166 | Values: 700.7 724.2 734.8 742.5 750.0 13001.7 13883.7 13903.4 13926.0 13963.4 13633.7 15759.8 16193.2 16255.7 16337.4 885.8 903.7 908.6 909.3 909.3 873.6 906.0 909.0 912.3 912.6 885.4 890.1 890.6 894.1 935.0 879.5 882.3 890.2 890.9 934.3 678.2 681.0 685.7 687.1 687.3 13544.3 13919.4 14026.0 14144.5 14153.0 15314.3 15436.7 15757.0 15758.4 15774.1 12553.2 12790.2 12799.7 12799.9 12804.6 2919.3 2995.2 3000.9 3017.4 3019.8 64441.0 65093.3 65751.3 67107.0 69980.0 12209.1 12528.6 12548.1 12582.5 12587.2 21460.7 21735.0 22148.1 22172.9 22200.2 5970.1 6085.7 6095.3 6097.3 6112.8 2914.7 2976.5 2982.4 2986.4 2989.4 3557.3 3584.2 3610.8 3636.4 3694.8 654.3 663.7 670.2 670.5 672.3 577.2 577.7 581.1 586.0 587.7 8997.8 9047.3 9293.9 9662.4 9825.5 600.5 606.6 616.3 617.1 625.5 4369.1 4405.6 4411.3 4432.8 4454.1 59116.7 59952.7 60582.4 61029.7 61643.0 54254.5 54712.0 55027.0 55266.0 56085.2 48552.8 49919.0 50239.2 50555.7 50809.5 8896.2 8989.3 9019.0 9071.5 9300.5 26409.7 26734.2 26927.6 27070.9 27566.9 31728.5 31816.8 31838.8 32032.0 32073.4 11174.0 11218.8 11380.3 11482.6 11482.7 12593.6 12709.2 12750.2 12783.9 12928.2 11881.1 12054.3 12085.9 12197.4 12207.0 11661.6 11813.4 11880.2 11922.4 11951.5 10013.8 10326.5 10770.8 10822.6 10837.3 7691.8 7714.0 7861.1 8064.8 8165.1 9423.5 9439.2 9584.0 9634.2 9917.7 07:50:03 INFO - PID 26166 | -------- Summary: end -------- 07:50:03 INFO - PID 26166 | 07:50:04 INFO - TEST-INFO | 26166: exit 0 07:50:04 INFO - TEST-OK | dromaeo_css | took 1120627ms 07:50:04 INFO - TEST-START | kraken 07:50:04 INFO - operating with platform_type : linux_ 07:50:04 INFO - Initialising browser for kraken test... 07:50:04 INFO - TEST-INFO | started process 26493 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpImjcHp/profile http://localhost:58095/getInfo.html) 07:50:07 INFO - PID 26493 | __metrics Screen width/height:1600/1200 07:50:07 INFO - PID 26493 | colorDepth:24 07:50:07 INFO - PID 26493 | Browser inner width/height: 1024/695 07:50:07 INFO - PID 26493 | __metrics 07:50:08 INFO - PID 26493 | 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" 07:50:08 INFO - PID 26493 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:09 INFO - TEST-INFO | 26493: exit 0 07:50:09 INFO - Browser initialized. 07:50:09 INFO - Running cycle 1/1 for kraken test... 07:50:09 INFO - TEST-INFO | started process 26585 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpImjcHp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:21 INFO - PID 26585 | RSS: Main: 149356544 07:50:21 INFO - PID 26585 | 07:50:24 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-beat-detection) 07:50:25 INFO - PID 26585 | RSS: Main: 221564928 07:50:25 INFO - PID 26585 | 07:50:28 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-dft) 07:50:28 INFO - PID 26585 | RSS: Main: 182837248 07:50:28 INFO - PID 26585 | 07:50:31 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-fft) 07:50:32 INFO - PID 26585 | RSS: Main: 266240000 07:50:32 INFO - PID 26585 | 07:50:34 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58095/tests/kraken/driver.html?testName=audio-oscillator) 07:50:35 INFO - PID 26585 | RSS: Main: 175489024 07:50:35 INFO - PID 26585 | 07:50:37 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58095/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:50:37 INFO - PID 26585 | RSS: Main: 179482624 07:50:37 INFO - PID 26585 | 07:50:41 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58095/tests/kraken/driver.html?testName=imaging-darkroom) 07:50:41 INFO - PID 26585 | RSS: Main: 239345664 07:50:41 INFO - PID 26585 | 07:50:45 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58095/tests/kraken/driver.html?testName=imaging-desaturate) 07:50:45 INFO - PID 26585 | RSS: Main: 239079424 07:50:45 INFO - PID 26585 | 07:50:48 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58095/tests/kraken/driver.html?testName=json-parse-financial) 07:50:49 INFO - PID 26585 | RSS: Main: 236449792 07:50:49 INFO - PID 26585 | 07:50:50 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58095/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:50:51 INFO - PID 26585 | RSS: Main: 201805824 07:50:51 INFO - PID 26585 | 07:50:53 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:50:53 INFO - PID 26585 | RSS: Main: 206209024 07:50:53 INFO - PID 26585 | 07:50:55 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:50:56 INFO - PID 26585 | RSS: Main: 215592960 07:50:56 INFO - PID 26585 | 07:50:59 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:00 INFO - PID 26585 | RSS: Main: 225501184 07:51:00 INFO - PID 26585 | 07:51:03 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:03 INFO - PID 26585 | RSS: Main: 202235904 07:51:03 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | Cycle 1(1): loaded http://localhost:58095/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58095/tests/kraken/driver.html?testName=ai-astar) 07:51:05 INFO - PID 26585 | RSS: Main: 271011840 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | __start_tp_report 07:51:05 INFO - PID 26585 | _x_x_mozilla_page_load 07:51:05 INFO - PID 26585 | _x_x_mozilla_page_load_details 07:51:05 INFO - PID 26585 | |i|pagename|runs| 07:51:05 INFO - PID 26585 | |0;ai-astar;103;113;113;114;112;110;112;113;111;112 07:51:05 INFO - PID 26585 | |1;audio-beat-detection;152;123;123;166;128;129;123;124;149;125 07:51:05 INFO - PID 26585 | |2;audio-dft;160;161;171;161;162;163;162;161;162;162 07:51:05 INFO - PID 26585 | |3;audio-fft;75;75;76;105;75;77;75;77;94;78 07:51:05 INFO - PID 26585 | |4;audio-oscillator;83;83;110;84;83;91;99;84;83;101 07:51:05 INFO - PID 26585 | |5;imaging-gaussian-blur;113;113;113;114;113;113;113;113;113;113 07:51:05 INFO - PID 26585 | |6;imaging-darkroom;150;149;149;149;150;149;148;149;149;150 07:51:05 INFO - PID 26585 | |7;imaging-desaturate;94;95;94;94;94;93;94;95;94;94 07:51:05 INFO - PID 26585 | |8;json-parse-financial;54;52;53;54;55;54;55;53;55;54 07:51:05 INFO - PID 26585 | |9;json-stringify-tinderbox;55;56;56;56;55;55;56;55;56;55 07:51:05 INFO - PID 26585 | |10;stanford-crypto-aes;83;85;82;85;82;81;82;82;83;81 07:51:05 INFO - PID 26585 | |11;stanford-crypto-ccm;134;133;133;131;134;131;148;140;176;130 07:51:05 INFO - PID 26585 | |12;stanford-crypto-pbkdf2;192;193;196;198;189;202;188;192;194;191 07:51:05 INFO - PID 26585 | |13;stanford-crypto-sha256-iterative;73;76;73;74;76;72;72;72;74;72 07:51:05 INFO - PID 26585 | __end_tp_report 07:51:05 INFO - PID 26585 | __start_cc_report 07:51:05 INFO - PID 26585 | _x_x_mozilla_cycle_collect,2060 07:51:05 INFO - PID 26585 | __end_cc_report 07:51:05 INFO - PID 26585 | __startTimestamp1494600665931__endTimestamp 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | ------- Summary: start ------- 07:51:05 INFO - PID 26585 | Number of tests: 14 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.50 stddev:3.13 (2.8%) stddev-sans-first:1.20 07:51:05 INFO - PID 26585 | Values: 103.0 113.0 113.0 114.0 112.0 110.0 112.0 113.0 111.0 112.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:128.50 stddev:15.55 (12.1%) stddev-sans-first:15.11 07:51:05 INFO - PID 26585 | Values: 152.0 123.0 123.0 166.0 128.0 129.0 123.0 124.0 149.0 125.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:3.10 (1.9%) stddev-sans-first:3.15 07:51:05 INFO - PID 26585 | Values: 160.0 161.0 171.0 161.0 162.0 163.0 162.0 161.0 162.0 162.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#3] audio-fft Cycles:10 Average:80.70 Median:76.50 stddev:10.30 (13.5%) stddev-sans-first:10.71 07:51:05 INFO - PID 26585 | Values: 75.0 75.0 76.0 105.0 75.0 77.0 75.0 77.0 94.0 78.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:83.50 stddev:9.84 (11.8%) stddev-sans-first:10.09 07:51:05 INFO - PID 26585 | Values: 83.0 83.0 110.0 84.0 83.0 91.0 99.0 84.0 83.0 101.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:51:05 INFO - PID 26585 | Values: 113.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 07:51:05 INFO - PID 26585 | Values: 150.0 149.0 149.0 149.0 150.0 149.0 148.0 149.0 149.0 150.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:51:05 INFO - PID 26585 | Values: 94.0 95.0 94.0 94.0 94.0 93.0 94.0 95.0 94.0 94.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 07:51:05 INFO - PID 26585 | Values: 54.0 52.0 53.0 54.0 55.0 54.0 55.0 53.0 55.0 54.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:56.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 07:51:05 INFO - PID 26585 | Values: 55.0 56.0 56.0 56.0 55.0 55.0 56.0 55.0 56.0 55.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:82.50 stddev:1.43 (1.7%) stddev-sans-first:1.51 07:51:05 INFO - PID 26585 | Values: 83.0 85.0 82.0 85.0 82.0 81.0 82.0 82.0 83.0 81.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:134.00 stddev:14.07 (10.5%) stddev-sans-first:14.81 07:51:05 INFO - PID 26585 | Values: 134.0 133.0 133.0 131.0 134.0 131.0 148.0 140.0 176.0 130.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.50 Median:193.50 stddev:4.22 (2.2%) stddev-sans-first:4.44 07:51:05 INFO - PID 26585 | Values: 192.0 193.0 196.0 198.0 189.0 202.0 188.0 192.0 194.0 191.0 07:51:05 INFO - PID 26585 | 07:51:05 INFO - PID 26585 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:73.50 stddev:1.58 (2.1%) stddev-sans-first:1.67 07:51:05 INFO - PID 26585 | Values: 73.0 76.0 73.0 74.0 76.0 72.0 72.0 72.0 74.0 72.0 07:51:05 INFO - PID 26585 | -------- Summary: end -------- 07:51:05 INFO - PID 26585 | 07:51:07 INFO - TEST-INFO | 26585: exit 0 07:51:07 INFO - TEST-OK | kraken | took 62946ms 07:51:07 INFO - SUITE-END | took 1183s 07:51:07 INFO - Completed test suite (00:19:43) 07:51:07 INFO - javascript benchmark 07:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6581.292934352413, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2669.5328317544427, 2707.350235943374, 2747.931301717457, 2747.9450410991785, 2750.174947515745, 5056.292119103319, 5171.276021357013, 5216.347956505437, 5251.947480525198, 5299.814506492274, 5207.921881171786, 5222.5038621331005, 5223.425423203445, 5297.655652382592, 5305.363356397228, 4337.371081193225, 4558.27067669173, 4640.3967484227005, 4682.274247491644, 4760.404949381327, 4647.88380290492, 4702.553257440538, 4716.127516409458, 4718.787654555536, 4793.400824896888, 4570.085982803453, 4610.907781844345, 4650.209464391061, 4650.25595904657, 4653.208954477747, 4614.838480653195, 4663.0207656392195, 4674.929876051828, 4726.503717109723, 4732.313814496879, 4624.3988281523525, 4638.281066434708, 4657.371254880615, 4662.230731929228, 4666.136764698507, 5116.232565115262, 5193.143131383317, 5237.685738855643, 5238.816641417532, 5291.576673866081, 5559.360673522557, 5571.247881536022, 5571.609987300891, 5576.414476479992, 5577.0, 5655.236543066674, 5727.255456790591, 5738.77044918207, 5742.712864356766, 5745.425457454263, 5273.762680679379, 5307.708076055854, 5319.787208511693, 5323.920141197885, 5324.281222035053, 5718.285214348207, 5810.709464526757, 5819.388964158702, 5824.650520968756, 5829.854253643693, 5374.677519348852, 5456.48163424474, 5460.399356070829, 5466.262054622575, 5578.832635020955, 5602.439756024365, 5615.354234263031, 5615.8596035098635, 5659.745311460994, 5664.518515926193, 5468.781248750005, 5489.58828087895, 5569.526590239875, 5571.331440227118, 5587.078132108238, 5617.859553511195, 5639.0, 5649.0, 5649.237352957381, 5674.177244299553, 4769.093872164278, 4776.14029474691, 4779.06808172403, 4780.68925519847, 4823.782929768169, 3990.580988996114, 4021.8994525137105, 4044.9797751011056, 4049.331860243086, 4111.753294802262, 4814.975925120352, 4818.68678535901, 4847.515248475195, 4859.271109333557, 4864.537868902448, 5684.232628595169, 5688.317401911797, 5688.857778555486, 5695.0, 5743.971280143573, 4688.929666055012, 4762.642801789811, 4801.4718380978065, 4803.423589169322, 4809.5430934061205, 5008.524190201925, 5087.4149472811105, 5142.742862856842, 5143.8456846294675, 5151.716655583904, 5224.399194092728, 5244.501772331622, 5248.212768084818, 5249.343832020997, 5250.65870718402, 5517.641353312182, 5679.17651940458, 5691.544676425959, 5700.3444603871085, 5705.030144875298, 5105.387353517602, 5219.2693022976055, 5219.425863155126, 5232.319798426181, 5245.895082098413, 5587.189857470726, 5590.692511911884, 5591.10542313228, 5593.776248949996, 5634.0, 5475.698836564027, 5553.416891226363, 5651.971740141273, 5656.886862262814, 5704.600677952503, 5454.181872719124, 5460.535854452257, 5516.365617953827, 5558.527525160407, 5563.13771365439, 5312.601554883322, 5409.86475338104, 5438.293021907127, 5495.450454954473, 5499.807506737245, 5343.332083489564, 5366.0, 5369.597280203922, 5400.675959442446, 5408.107662235687, 5481.205225242397, 5506.03644362243, 5507.173923911445, 5507.642003269774, 5509.669419834822, 5267.420583735862, 5342.278792362981, 5345.251664766858, 5352.357717073976, 5402.378726446509], "value": 5110.221802944884, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.7320212111973, 690.5787469643518, 718.5939943931678, 1023.4780262066344, 1035.6012935020015, 25246.0, 32217.51673724896, 32281.19297017573, 32364.19089522763, 32370.144478328293, 34862.3027539449, 35012.0, 35169.12077198071, 35216.94349169522, 35265.64734352662, 5199.87000324993, 6972.1284839395075, 7103.751368702096, 7118.608476533788, 7119.644017799115, 5278.181881808326, 5354.866128346784, 5355.196720491918, 6295.874082518347, 6997.545159564637, 7077.3984211342095, 7099.254578269259, 7116.288371162894, 7122.8575428491395, 7171.605561694105, 7021.0, 7026.789196324067, 7062.57624542524, 7073.787786366366, 7082.220955694844, 6969.989351544048, 7019.859602807966, 7067.363937245621, 7073.681684324218, 7079.185893622248, 30597.694023059707, 30732.53901191484, 30789.538156927665, 30820.691793082005, 30840.383192336252, 2997.3405850713025, 3025.0319897632767, 3025.7428118609946, 3031.4846476099115, 3031.9696803031907, 24102.156424525234, 24127.0, 24203.063754899566, 24219.273421797374, 24231.636525452133, 3066.1721335239586, 3105.3168302973536, 3117.610298712661, 3126.6716994715684, 3134.514150306706, 137435.31282343625, 137723.0, 138413.0, 138447.0, 139089.0, 40719.59280407158, 40723.796381017906, 40829.79585102115, 40830.0, 40834.38748418776, 42320.017197162124, 42461.78769106135, 42552.361714574305, 42580.78709606432, 42633.78683106565, 11775.823362649568, 11854.94072529632, 11859.0, 11879.22785018971, 11887.227330223508, 9415.246780257563, 9430.245580353556, 9436.952815236018, 9441.15029647335, 9444.716658500256, 9906.80186396268, 10131.645392411378, 11569.42152892352, 11886.762264754656, 12154.756904861853, 4738.786754596052, 4748.477667456577, 4878.853634390974, 4879.902401952012, 4929.630277729185, 835.2691395029349, 893.4237416866085, 898.8112496375834, 924.3806649544821, 931.9953400233092, 20329.49176270605, 20527.0, 21022.0, 21151.682724759143, 21310.467238319165, 966.5119114846957, 975.687779910436, 985.3004366899438, 994.1798016636304, 1003.1423133220991, 10755.731106722395, 11180.552777888794, 11188.66434006981, 11189.944050279697, 11297.0, 150890.0, 150915.0, 151141.0, 151818.0, 152057.0, 39641.60358396437, 39717.0, 39729.20541589152, 39789.2042159161, 39870.0, 1185.4418398680286, 1219.9878001219874, 1232.0, 1236.5177580743518, 1238.1518659718122, 19160.425187244404, 19987.500312492302, 20320.390388288375, 20656.690149648068, 20900.1639934407, 29297.85351073231, 29346.559801602565, 29361.55957660594, 29438.11685649434, 29449.85275073611, 143788.0, 144357.0, 144477.0, 144602.0, 145018.0, 28626.57060144141, 28668.139955801358, 28795.42409151847, 28827.135185944455, 28893.855530722212, 28986.42027159487, 29931.55102673418, 32211.67788322087, 33951.4907276396, 35444.0, 25298.620520692573, 26295.474090518466, 26431.735682642928, 27638.170854874385, 27953.72046279511, 27039.918403263648, 27177.86411067932, 28202.576961346007, 28224.85887570549, 28987.7101228985, 31040.0687979361, 31050.84474577703, 31066.0, 31133.53299700461, 31137.0, 26138.86930565335, 26301.712285964255, 26586.803593837936, 27207.0, 27353.520408673376, 21124.366269011953, 21940.341789746333, 22115.0, 22136.667949981085, 22177.556448870608], "value": 14042.22743731452, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.1855769902495, 411.2494697177652, 425.5212885503808, 438.57458265482484, 439.9714018588792, 15396.461123860667, 16176.676466470679, 16920.0, 16995.575110622227, 16997.0, 17273.13634318282, 17391.217395217212, 17624.383146589873, 17944.730829037573, 18093.366732164377, 5239.21411788231, 5303.602229832759, 5424.918626220609, 5443.727813609324, 5450.754716037788, 5367.865803354928, 5472.0, 5474.206240095204, 5483.150111732688, 5483.616146869721, 5226.608004399666, 5310.4424035476295, 5448.945510544883, 5450.236966824647, 5481.643693159952, 5338.092524270864, 5359.0, 5380.704061276629, 5397.325334333228, 5403.027455058088, 4835.1296766582, 4877.365942427497, 4887.462379138327, 4892.021595680878, 4896.045271172123, 20802.895985519976, 20822.58354832895, 20902.0, 20993.580128397345, 21005.68491472629, 1660.8090069642026, 1719.759233707282, 1726.9136543172917, 1727.5162954372795, 1727.7149270370373, 13649.385777640031, 14413.0, 14444.422223111063, 14488.348024338824, 14524.273786310641, 2027.3208475160839, 2044.37646517812, 2106.3575609439113, 2110.9788902110936, 2120.90455929482, 77808.61095694486, 78256.21743782546, 78284.21715782826, 79156.60421697912, 81392.0, 10739.248252622241, 10912.656165665481, 10985.285956412808, 11035.503402346914, 11298.491567879466, 15817.288222030036, 15862.682746345008, 15869.04785712861, 15923.52229433119, 15944.681106377808, 7159.122687867008, 7206.863866006066, 7321.194668586451, 7324.267573242633, 7345.15530713964, 6392.904106438407, 6464.159659244268, 6635.203775546869, 6646.4350530205475, 6651.534392592472, 6780.254172041072, 6931.861362772817, 7011.649417529103, 7055.753048643272, 7080.398166155832, 1999.0, 2001.0895042755512, 2038.7349644546114, 2063.298478517281, 2070.3995841205883, 843.7510934274404, 893.8033632600817, 898.955052247385, 903.6295119001178, 911.7128104647015, 17431.825681743274, 17601.207945642476, 17759.200835962154, 17990.44684436737, 18090.728639070425, 867.3234876796109, 891.7681402835309, 897.7620930453435, 905.800723840754, 911.6262332443666, 9245.861312080324, 9371.484568348806, 9468.14786669203, 9745.805083898282, 9756.268279879037, 336613.0, 340193.6719175706, 347833.0, 348028.0, 349354.0, 70298.0, 73348.0, 73841.6307918457, 74528.62735686287, 75462.0, 1023.8157131716363, 1035.089121573011, 1069.545443186658, 1073.388168743824, 1075.381655548063, 14445.0, 14578.271086445424, 14632.853671463576, 14659.92670036643, 15173.241337932839, 36922.8153859229, 37012.152751741014, 37380.626193738804, 37660.62339376681, 37960.430593540565, 45628.54371456243, 45970.0, 47837.91566133446, 48543.271850921556, 48841.0, 7958.442908996315, 8039.879401809095, 8212.83574328522, 8256.917430825615, 8281.37889658266, 11742.88257117418, 11916.344601047025, 11928.76142477163, 12301.32342721159, 12325.876741232833, 10585.8941410588, 10997.725056873322, 11120.61077862271, 11202.215844891105, 11687.682104642197, 10402.791944161227, 10597.576096956036, 10780.622678206539, 10823.67528974132, 10853.728656783644, 11551.537938482703, 11554.0, 11604.0, 11725.88274117282, 11733.237339572901, 8496.150384961702, 8505.149485051692, 8593.742187734379, 8637.136286371313, 8708.651653933772, 10545.420001899662, 10559.63041293551, 10583.841242381524, 10664.200184986312, 11049.502772375423], "value": 8674.028558788339, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1089.7548051688375, 1230.464747834692, 1274.108124312981, 1289.1040726694946, 1292.7866901961177, 8275.503469791816, 10285.897141028587, 10634.57461701531, 10643.627473038427, 10656.573737050508, 10416.843747343795, 10528.684139475828, 10529.315594486354, 10531.473426328672, 10541.0, 1787.4012205911035, 2187.223535644847, 2218.279059305724, 2219.5671843990426, 2225.610518159324, 1925.2491528303972, 2169.1215057901577, 2245.5733410652006, 2266.036934302923, 2279.794818466338, 2180.9563808723815, 2190.4742861713235, 2190.496185877249, 2205.0297868937696, 2208.7901649343366, 2121.1621409543222, 2132.1577976699273, 2132.4029271803765, 2133.4666333416644, 2134.039682143034, 2107.093949601671, 2119.5231073008604, 2130.041481333406, 2145.2062736787334, 2185.4645611825044, 9713.90286097144, 9798.314118011742, 9806.754831129207, 9810.264230182765, 9822.75443113928, 5134.460881607452, 5313.3092697949405, 5314.777601151718, 5323.361196656388, 5328.520433161034, 3182.931094788406, 3310.6027276726704, 3335.0495108894092, 3365.2428203654226, 3369.6630336966355, 1112.821948488246, 1150.654803558929, 1182.0425455381169, 1189.0190592761007, 1191.5650787462514, 15200.391984320613, 15208.467703630431, 15210.847891521165, 15214.847851521454, 15217.61955951099, 4929.457759646436, 5019.47295533971, 5178.922316165261, 5252.632315737938, 5294.708791016454, 12714.809277860839, 12718.427670754838, 12725.363731813371, 12732.363381830872, 12734.299613521345, 5812.941870581325, 5813.418658134237, 5819.127130930395, 5828.358880523139, 5839.386864379264, 4981.626378021663, 5088.0, 5144.588432925357, 5146.125158723026, 5172.0, 4019.8191081401405, 4082.897927551835, 4139.19285739278, 4155.065110350147, 4213.115245798355, 1312.4487715159655, 1315.5658632651202, 1342.6506361107024, 1343.8454577723492, 1352.4860552989999, 670.8691805097959, 696.7143471176793, 696.8292768271705, 711.5161690050816, 721.3940290156294, 5519.69641669712, 5724.16999535065, 5743.368229494752, 5789.189513468118, 5945.821625351246, 522.1671434062664, 536.9221462887859, 539.4524557574066, 801.7314199743034, 832.9250367466957, 3566.465030245432, 3629.3467175908063, 3651.4887915691825, 3718.535183102112, 3765.1151979284846, 12221.144519883701, 12500.499980000699, 12691.682707932376, 12714.745705085847, 12760.936195318964, 12548.435320410603, 12633.305168215837, 12667.746645067231, 12667.873321266854, 12763.808542871864, 7183.892241616275, 7217.530860494051, 7354.043974283309, 7363.889541656879, 7375.741849035258, 5307.336582927134, 5348.679079255257, 5358.866028349323, 5428.402875683751, 5557.805476808292, 11395.202335836411, 11396.94301528487, 11427.0, 11480.655580332603, 11632.243904146204, 9444.763880903032, 11253.606123785628, 11502.424878756196, 11614.361210133524, 12001.939990299992, 7895.921040789519, 8112.026556812984, 8114.756557303291, 8125.512469251864, 8127.715529956659, 8739.21347078766, 9027.729168124966, 9563.330566860532, 9757.21942244633, 9813.509324533887, 8192.713255036284, 8392.790180245544, 8424.536650484282, 8449.577521124042, 8579.785505362415, 8143.5928203590765, 8220.506769593843, 8227.917720822716, 8424.326053915549, 8480.0, 7416.851662966818, 7495.550266983998, 7502.512336698315, 7517.774466766005, 7554.848903022019, 5040.7479626019285, 5078.619103567321, 5171.379434467888, 5195.350581177353, 5239.633225674319, 5246.737663116905, 5323.920141197807, 5365.490278423544, 5385.9192112119135, 5443.863903402446], "value": 4706.642888836749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.3331819293525, 386.44738024624786, 387.35118676217337, 389.82652719539783, 390.20203683467304, 5230.503102205289, 5301.204819277111, 5303.920441193385, 5332.280142180804, 5346.117890548065, 5062.468440813716, 5150.896982060366, 5176.171812509999, 5217.58259339252, 5230.137027390486, 4088.1619268049976, 4098.159877225176, 4124.876253712393, 4129.339305711094, 4132.938005929914, 4117.3206420940505, 4121.814518346681, 4125.257453658341, 4132.173565286939, 4144.606262405082, 4032.3951407288996, 4057.660971879273, 4092.3247664135383, 4107.240160570304, 4120.896977575555, 4071.7149799514045, 4073.6333729964445, 4079.877603671865, 4084.5711200323835, 4103.107309346919, 3695.7043436525014, 3696.2977034363385, 3702.2965636529243, 3714.888553343404, 3717.70258379329, 5122.538971492546, 5125.923111153335, 5127.87180320495, 5132.5124113209185, 5134.0, 4738.620910327166, 4894.388201474816, 4945.629077819178, 4949.653524253304, 4953.182724850395, 5605.859853503654, 5624.887502249973, 5652.8869422611315, 5655.773769049233, 5670.801521946753, 4178.26880295947, 4268.274393353137, 4272.252355837747, 4281.721688090295, 4291.098869237433, 5636.943630563683, 5824.912626310609, 5884.146798714205, 5903.291605007384, 5953.970230148865, 4341.82632694695, 4421.38100665907, 4423.778811059498, 4440.977795111004, 4471.821127154877, 4495.325701144854, 4541.409616749801, 4544.931826022612, 4548.931766023512, 4551.431071116111, 4494.0, 4740.715557066518, 4751.03060587133, 4999.47505511916, 5000.0, 5518.117101263779, 5623.325200975909, 5624.71876406178, 5628.774849005994, 5646.0, 1553.5572361876812, 1574.1184936435632, 1576.7398379267522, 1577.4321244352025, 1580.6601580660213, 1092.9781404371868, 1131.8698349689892, 1142.5829572206085, 1147.5180424221846, 1148.3454430974432, 4881.8535443936735, 4901.240307752304, 4915.21356582945, 4933.753312334369, 4940.098062246061, 5708.65748055118, 5765.019946609086, 5765.048766953406, 5784.739686714024, 5796.203177015139, 4645.581897629162, 4872.4883887192045, 4911.803527858846, 4912.754362281871, 4955.479674634112, 3063.862126204326, 3087.2899233176495, 3091.969080309213, 3092.1805721483825, 3104.7205751482024, 5299.258103865462, 5342.305500284938, 5400.351957765093, 5406.675599463966, 5411.350637923474, 5941.287045554561, 5946.43508866657, 5968.432998865101, 5975.820725378246, 5987.221661184019, 5368.087425137736, 5383.380911195184, 5387.626155330444, 5388.622796404293, 5391.406945236099, 5673.716314184357, 5765.0, 5814.534837212928, 5841.6856207353, 5843.14763133861, 5447.12845944647, 5683.60214784961, 5717.599768016199, 5730.656160630376, 5731.996900542305, 5093.239627840109, 5238.842834714965, 5260.316158899319, 5268.6575372600655, 5278.86802829917, 3872.806359682061, 3990.7605543667473, 4015.5582885881945, 4028.91942161161, 4035.1122752994293, 5637.08106488194, 5773.422657734361, 5789.739461724317, 5817.069268916953, 5830.067189249699, 5630.915536266877, 5634.126710359901, 5635.351934527582, 5656.406077361982, 5657.179708942263, 5627.184058311604, 5647.435256474319, 5652.943470565242, 5669.659820410789, 5677.943220567741, 5451.972740136274, 5502.202180683858, 5530.6128570999645, 5532.336119665666, 5543.085390910454, 5673.914891276717, 5681.289838770153, 5684.118961560965, 5689.7439615218245, 5694.715264236855, 5401.702906340031, 5502.944970550243, 5505.917411238915, 5508.724563771715, 5529.22590837275], "value": 4292.6575999257575, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.6951975890491, 724.235931092697, 734.808949673085, 742.465424894076, 749.99625001875, 13001.67495812604, 13883.722325553495, 13903.443862245498, 13926.0, 13963.37164827584, 13633.659158521043, 15759.842401575981, 16193.190340482988, 16255.674886502264, 16337.428190013352, 885.809550946548, 903.7288813355967, 908.6410867707252, 909.2998391238742, 909.3089252168367, 873.6243415331405, 906.0, 908.954552272387, 912.2884150362695, 912.6486302773446, 885.3536918049828, 890.0654312971386, 890.6036813617943, 894.0701670262919, 935.0088905759916, 879.5294517433175, 882.3294296334767, 890.2388457868494, 890.9242714369221, 934.2712684106408, 678.220046946016, 681.0329332348077, 685.7222824755931, 687.0861753867345, 687.3229868579446, 13544.322783860865, 13919.373628186755, 14026.0, 14144.504942327072, 14153.0, 15314.310856011507, 15436.691266174614, 15757.0, 15758.369665213379, 15774.132422716746, 12553.184043037174, 12790.232586044775, 12799.74400511994, 12799.87200127996, 12804.551840685624, 2919.2701824543865, 2995.1912983494553, 3000.8949686761075, 3017.411604737077, 3019.818810871333, 64441.0, 65093.3490665092, 65751.342486575, 67107.0, 69979.99999999949, 12209.145359824726, 12528.624141275777, 12548.121631485708, 12582.496700132075, 12587.24476531411, 21460.678089828663, 21735.0, 22148.114075436803, 22172.889135554542, 22200.222992195195, 5970.0746384310505, 6085.726142323518, 6095.2685677718955, 6097.2683278006925, 6112.7554897804475, 2914.7231013053724, 2976.5088760354297, 2982.3587928595457, 2986.3728616990675, 2989.4170636726067, 3557.2529768748855, 3584.175639602906, 3610.765300255475, 3636.4181730922924, 3694.833732482045, 654.2672207128047, 663.741140955028, 670.2158474584747, 670.497127154634, 672.2739441403272, 577.1919312961887, 577.6707276852238, 581.0818906128308, 585.9912101318398, 587.7267070812093, 8997.775055623531, 9047.321450891079, 9293.907060929305, 9662.3719458235, 9825.508724563742, 600.5105838741499, 606.5572132343326, 616.3343588923951, 617.0713076819358, 625.5121005615628, 4369.082492676574, 4405.559444055569, 4411.338299255073, 4432.84485043022, 4454.10917816438, 59116.704416478504, 59952.70023649854, 60582.39417605856, 61029.69485152634, 61643.0, 54254.45745542573, 54712.0, 55027.0, 55266.0, 56085.15872261919, 48552.75723621359, 49919.0, 50239.24641130386, 50555.74722126366, 50809.49190508122, 8896.243819275434, 8989.289928748836, 9019.0, 9071.455712657262, 9300.488473133772, 26409.735902641496, 26734.197974060808, 26927.596086058333, 27070.864645676647, 27566.862165689043, 31728.524072139397, 31816.840915795274, 31838.840805795822, 32032.0, 32073.35853282968, 11174.0, 11218.831717524406, 11380.317180969168, 11482.598109066143, 11482.655520334403, 12593.559225427067, 12709.17390369623, 12750.234985900875, 12783.872161278268, 12928.224306541637, 11881.108916831445, 12054.276743395425, 12085.939570302093, 12197.390130493617, 12206.962408195403, 11661.59184428575, 11813.350265735467, 11880.227785193934, 11922.403879806148, 11951.521939122686, 10013.849792252977, 10326.535305911402, 10770.838437423288, 10822.621208257673, 10837.295575787548, 7691.807704807425, 7713.961430192813, 7861.135275119618, 8064.798380040546, 8165.14266002085, 9423.528823558932, 9439.244860411289, 9583.952080239555, 9634.181094607036, 9917.65288214909], "value": 6461.041549703828, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 114.0, 112.0, 110.0, 112.0, 113.0, 111.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 123.0, 166.0, 128.0, 129.0, 123.0, 124.0, 149.0, 125.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 171.0, 161.0, 162.0, 163.0, 162.0, 161.0, 162.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 105.0, 75.0, 77.0, 75.0, 77.0, 94.0, 78.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 110.0, 84.0, 83.0, 91.0, 99.0, 84.0, 83.0, 101.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 149.0, 150.0, 149.0, 148.0, 149.0, 149.0, 150.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 54.0, 55.0, 54.0, 55.0, 53.0, 55.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 56.0, 55.0, 55.0, 56.0, 55.0, 56.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 82.0, 85.0, 82.0, 81.0, 82.0, 82.0, 83.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 133.0, 133.0, 131.0, 134.0, 131.0, 148.0, 140.0, 176.0, 130.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 193.0, 196.0, 198.0, 189.0, 202.0, 188.0, 192.0, 194.0, 191.0], "value": 193.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 73.0, 74.0, 76.0, 72.0, 72.0, 72.0, 74.0, 72.0], "value": 73.4, "unit": "score"}]}]} 07:51:07 INFO - Return code: 0 07:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:51:07 INFO - # TBPL SUCCESS # 07:51:07 INFO - Running post-action listener: _resource_record_post_action 07:51:07 INFO - [mozharness: 2017-05-12 14:51:07.748959Z] Finished run-tests step (success) 07:51:07 INFO - Running post-run listener: _resource_record_post_run 07:51:07 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 367337472; Read time: 8; Write time: 235288 07:51:07 INFO - TinderboxPrint: CPU usage
12.9% 07:51:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 07:51:07 INFO - TinderboxPrint: I/O write bytes / time
367,337,472 / 235,288 07:51:07 INFO - TinderboxPrint: CPU idle
8,338.8 (87.1%) 07:51:07 INFO - TinderboxPrint: CPU user
1,194.6 (12.5%) 07:51:07 INFO - TinderboxPrint: Swap in / out
0 / 0 07:51:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114364416; Read time: 0; Write time: 100092 07:51:07 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 252973056; Read time: 0; Write time: 135196 07:51:08 INFO - Running post-run listener: _upload_blobber_files 07:51:08 INFO - Blob upload gear active. 07:51:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:51:08 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:09 INFO - (blobuploader) - INFO - Done attempting. 07:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:10 INFO - (blobuploader) - INFO - Done attempting. 07:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:13 INFO - (blobuploader) - INFO - Done attempting. 07:51:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:51:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:14 INFO - (blobuploader) - INFO - Done attempting. 07:51:14 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:14 INFO - Return code: 0 07:51:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1266c4a700912cf622e7559094eebd3c0c4d00a207086afecec74ee483474b37f8a941ca4ddddf85dafbcec5af8d56cd51d91d7fa489b5af11bc55fc6a40d3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f271dec7f653d3294cdd2f7126d6d7bdfc10abe3dd2b7e201ded87b1ada4b70ae8890e41eb9816cf0cd9e4c707f02b0a04cb9663dd6035f67231e116866d4876", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81038ada66e640d7c7d4207855cf2e2a651819707609d976a9b7f05240a0b1223658cc6b33dd6e3d3008bc80c8de5382b900b4238a490e5b2b2f45d12d21cc75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ce899a63f55c837fa044cf3c27f1ce8359cd3b9e917d6d4ea15523be2626cc0095ee01b3cbcc6cc9f1ae8a974679a8a3888226ec3494d715e509299d58942c5"} 07:51:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:14 INFO - Contents: 07:51:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1266c4a700912cf622e7559094eebd3c0c4d00a207086afecec74ee483474b37f8a941ca4ddddf85dafbcec5af8d56cd51d91d7fa489b5af11bc55fc6a40d3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f271dec7f653d3294cdd2f7126d6d7bdfc10abe3dd2b7e201ded87b1ada4b70ae8890e41eb9816cf0cd9e4c707f02b0a04cb9663dd6035f67231e116866d4876", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81038ada66e640d7c7d4207855cf2e2a651819707609d976a9b7f05240a0b1223658cc6b33dd6e3d3008bc80c8de5382b900b4238a490e5b2b2f45d12d21cc75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ce899a63f55c837fa044cf3c27f1ce8359cd3b9e917d6d4ea15523be2626cc0095ee01b3cbcc6cc9f1ae8a974679a8a3888226ec3494d715e509299d58942c5"} 07:51:14 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:14 INFO - Copying logs to upload dir... 07:51:14 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.584034 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-05-12 07:51:14.737439) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:51:14.740963) ========= 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=bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622 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/c1266c4a700912cf622e7559094eebd3c0c4d00a207086afecec74ee483474b37f8a941ca4ddddf85dafbcec5af8d56cd51d91d7fa489b5af11bc55fc6a40d3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f271dec7f653d3294cdd2f7126d6d7bdfc10abe3dd2b7e201ded87b1ada4b70ae8890e41eb9816cf0cd9e4c707f02b0a04cb9663dd6035f67231e116866d4876", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81038ada66e640d7c7d4207855cf2e2a651819707609d976a9b7f05240a0b1223658cc6b33dd6e3d3008bc80c8de5382b900b4238a490e5b2b2f45d12d21cc75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ce899a63f55c837fa044cf3c27f1ce8359cd3b9e917d6d4ea15523be2626cc0095ee01b3cbcc6cc9f1ae8a974679a8a3888226ec3494d715e509299d58942c5"} build_url:https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/On88SKLTSaSVQZnFn_9u2Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1266c4a700912cf622e7559094eebd3c0c4d00a207086afecec74ee483474b37f8a941ca4ddddf85dafbcec5af8d56cd51d91d7fa489b5af11bc55fc6a40d3f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f271dec7f653d3294cdd2f7126d6d7bdfc10abe3dd2b7e201ded87b1ada4b70ae8890e41eb9816cf0cd9e4c707f02b0a04cb9663dd6035f67231e116866d4876", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81038ada66e640d7c7d4207855cf2e2a651819707609d976a9b7f05240a0b1223658cc6b33dd6e3d3008bc80c8de5382b900b4238a490e5b2b2f45d12d21cc75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ce899a63f55c837fa044cf3c27f1ce8359cd3b9e917d6d4ea15523be2626cc0095ee01b3cbcc6cc9f1ae8a974679a8a3888226ec3494d715e509299d58942c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:51:14.767262) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:51:14.767670) ========= 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=bb9b54edd4a927dc735d112f000001d9-1494599417.404815-856299622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:51:14.851868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:51:14.852367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:51:14.852766) ========= ========= Total master_lag: 0.16 =========